{"level":"warn","ts":"2026-04-14T05:39:16.62021Z","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-14T05:39:16.620307Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-14T05:39:16.620381Z","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-14T05:39:16.620394Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-14T05:39:16.620445Z","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-14T05:39:16.621284Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-14T05:39:16.621362Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-14T05:39:16.627084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.228681ms"} {"level":"info","ts":"2026-04-14T05:39:16.635169Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-14T05:39:16.635266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T05:39:16.635323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-14T05:39:16.635338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T05:39:16.635382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-14T05:39:16.635547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-14T05:39:16.644421Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T05:39:16.64614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T05:39:16.647554Z","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-14T05:39:16.657321Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T05:39:16.657759Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T05:39:16.657816Z","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-14T05:39:16.657843Z","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-14T05:39:16.657852Z","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-14T05:39:16.661749Z","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-14T05:39:16.661933Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-14T05:39:16.661972Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-14T05:39:16.662027Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T05:39:16.662071Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T05:39:16.662688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-14T05:39:16.662807Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-14T05:39:16.736307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T05:39:16.736356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T05:39:16.73638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-14T05:39:16.736392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-14T05:39:16.736397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-14T05:39:16.736406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-14T05:39:16.736413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-14T05:39:16.738583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:39:16.740136Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T05:39:16.740474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T05:39:16.740518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T05:39:16.741364Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:39:16.742575Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:39:16.742641Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:39:16.742675Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T05:39:16.742695Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T05:39:16.744289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T05:39:16.745555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-14T05:39:21.760716Z","caller":"traceutil/trace.go:171","msg":"trace[2087508399] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"216.14243ms","start":"2026-04-14T05:39:21.544548Z","end":"2026-04-14T05:39:21.760691Z","steps":["trace[2087508399] 'process raft request' (duration: 185.43969ms)","trace[2087508399] 'compare' (duration: 30.530007ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:21.760722Z","caller":"traceutil/trace.go:171","msg":"trace[859286244] transaction","detail":"{read_only:false; number_of_response:0; response_revision:224; }","duration":"208.882491ms","start":"2026-04-14T05:39:21.55182Z","end":"2026-04-14T05:39:21.760703Z","steps":["trace[859286244] 'process raft request' (duration: 208.852711ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:21.760809Z","caller":"traceutil/trace.go:171","msg":"trace[2011327953] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"215.514359ms","start":"2026-04-14T05:39:21.545273Z","end":"2026-04-14T05:39:21.760787Z","steps":["trace[2011327953] 'process raft request' (duration: 215.362976ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.01283Z","caller":"traceutil/trace.go:171","msg":"trace[1659759354] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"451.700043ms","start":"2026-04-14T05:39:21.561092Z","end":"2026-04-14T05:39:22.012792Z","steps":["trace[1659759354] 'process raft request' (duration: 390.152091ms)","trace[1659759354] 'compare' (duration: 61.338068ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:22.012971Z","caller":"traceutil/trace.go:171","msg":"trace[221892382] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"431.597018ms","start":"2026-04-14T05:39:21.581352Z","end":"2026-04-14T05:39:22.01295Z","steps":["trace[221892382] 'process raft request' (duration: 431.480066ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.01295Z","caller":"traceutil/trace.go:171","msg":"trace[1845659540] transaction","detail":"{read_only:false; number_of_response:0; response_revision:225; }","duration":"433.744819ms","start":"2026-04-14T05:39:21.579143Z","end":"2026-04-14T05:39:22.012888Z","steps":["trace[1845659540] 'process raft request' (duration: 433.581286ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.013006Z","caller":"traceutil/trace.go:171","msg":"trace[1919088569] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"248.301968ms","start":"2026-04-14T05:39:21.764674Z","end":"2026-04-14T05:39:22.012976Z","steps":["trace[1919088569] 'process raft request' (duration: 248.263597ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.01309Z","caller":"traceutil/trace.go:171","msg":"trace[1419229975] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"432.27756ms","start":"2026-04-14T05:39:21.580794Z","end":"2026-04-14T05:39:22.013071Z","steps":["trace[1419229975] 'process raft request' (duration: 431.973545ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.013062Z","caller":"traceutil/trace.go:171","msg":"trace[823472362] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"431.324523ms","start":"2026-04-14T05:39:21.581687Z","end":"2026-04-14T05:39:22.013011Z","steps":["trace[823472362] 'process raft request' (duration: 431.214561ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.013104Z","caller":"traceutil/trace.go:171","msg":"trace[2120919663] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:231; }","duration":"251.352946ms","start":"2026-04-14T05:39:21.761739Z","end":"2026-04-14T05:39:22.013092Z","steps":["trace[2120919663] 'read index received' (duration: 189.662031ms)","trace[2120919663] 'applied index is now lower than readState.Index' (duration: 61.689285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:22.013366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.918069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:39:22.013454Z","caller":"traceutil/trace.go:171","msg":"trace[1881801901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:230; }","duration":"250.053732ms","start":"2026-04-14T05:39:21.763387Z","end":"2026-04-14T05:39:22.013441Z","steps":["trace[1881801901] 'agreement among raft nodes before linearized reading' (duration: 249.906139ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.013478Z","caller":"traceutil/trace.go:171","msg":"trace[48647909] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"431.661379ms","start":"2026-04-14T05:39:21.581378Z","end":"2026-04-14T05:39:22.01304Z","steps":["trace[48647909] 'process raft request' (duration: 431.495286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:22.013837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.561081Z","time spent":"451.834725ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.014048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.581345Z","time spent":"432.18227ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.014165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.581324Z","time spent":"431.693559ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.014233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.579121Z","time spent":"433.924883ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.013267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.49601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3697"} {"level":"info","ts":"2026-04-14T05:39:22.014469Z","caller":"traceutil/trace.go:171","msg":"trace[1431166843] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:230; }","duration":"252.748343ms","start":"2026-04-14T05:39:21.761702Z","end":"2026-04-14T05:39:22.01445Z","steps":["trace[1431166843] 'agreement among raft nodes before linearized reading' (duration: 251.425917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:22.014456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.580781Z","time spent":"432.335231ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.01525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:21.581672Z","time spent":"431.476176ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.389173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.316327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:39:22.389287Z","caller":"traceutil/trace.go:171","msg":"trace[513216666] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"372.188638ms","start":"2026-04-14T05:39:22.01707Z","end":"2026-04-14T05:39:22.389258Z","steps":["trace[513216666] 'process raft request' (duration: 159.588385ms)","trace[513216666] 'compare' (duration: 212.091773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:22.38957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:22.017055Z","time spent":"372.422252ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:39:22.391342Z","caller":"traceutil/trace.go:171","msg":"trace[196746686] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:238; }","duration":"371.699379ms","start":"2026-04-14T05:39:22.01962Z","end":"2026-04-14T05:39:22.39132Z","steps":["trace[196746686] 'read index received' (duration: 157.052286ms)","trace[196746686] 'applied index is now lower than readState.Index' (duration: 214.646213ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:22.391393Z","caller":"traceutil/trace.go:171","msg":"trace[1600908914] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"373.987002ms","start":"2026-04-14T05:39:22.017387Z","end":"2026-04-14T05:39:22.391374Z","steps":["trace[1600908914] 'process raft request' (duration: 373.775377ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.391557Z","caller":"traceutil/trace.go:171","msg":"trace[1218989653] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"371.820461ms","start":"2026-04-14T05:39:22.019724Z","end":"2026-04-14T05:39:22.391545Z","steps":["trace[1218989653] 'process raft request' (duration: 371.555036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:22.391645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:22.019718Z","time spent":"371.874342ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:22.39169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.060185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3697"} {"level":"info","ts":"2026-04-14T05:39:22.391768Z","caller":"traceutil/trace.go:171","msg":"trace[565654003] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:233; }","duration":"372.142977ms","start":"2026-04-14T05:39:22.019612Z","end":"2026-04-14T05:39:22.391755Z","steps":["trace[565654003] 'agreement among raft nodes before linearized reading' (duration: 371.967453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:22.391839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:22.019591Z","time spent":"372.236458ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3720,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-14T05:39:22.392176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:22.017376Z","time spent":"374.137885ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:22.590909Z","caller":"traceutil/trace.go:171","msg":"trace[852090059] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"188.126072ms","start":"2026-04-14T05:39:22.402754Z","end":"2026-04-14T05:39:22.59088Z","steps":["trace[852090059] 'process raft request' (duration: 164.725193ms)","trace[852090059] 'compare' (duration: 23.037423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:22.591068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.34656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:22.591139Z","caller":"traceutil/trace.go:171","msg":"trace[1618205455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:236; }","duration":"171.477532ms","start":"2026-04-14T05:39:22.419646Z","end":"2026-04-14T05:39:22.591124Z","steps":["trace[1618205455] 'agreement among raft nodes before linearized reading' (duration: 171.341459ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.591153Z","caller":"traceutil/trace.go:171","msg":"trace[1321310102] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"185.312298ms","start":"2026-04-14T05:39:22.405822Z","end":"2026-04-14T05:39:22.591134Z","steps":["trace[1321310102] 'process raft request' (duration: 184.962912ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.590929Z","caller":"traceutil/trace.go:171","msg":"trace[308551459] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:241; }","duration":"171.223378ms","start":"2026-04-14T05:39:22.419677Z","end":"2026-04-14T05:39:22.5909Z","steps":["trace[308551459] 'read index received' (duration: 50.163263ms)","trace[308551459] 'applied index is now lower than readState.Index' (duration: 121.058835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:22.609573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.64949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-14T05:39:22.609581Z","caller":"traceutil/trace.go:171","msg":"trace[77582799] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"113.477159ms","start":"2026-04-14T05:39:22.496018Z","end":"2026-04-14T05:39:22.609496Z","steps":["trace[77582799] 'process raft request' (duration: 113.420368ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.609654Z","caller":"traceutil/trace.go:171","msg":"trace[539763396] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:237; }","duration":"116.772162ms","start":"2026-04-14T05:39:22.492862Z","end":"2026-04-14T05:39:22.609634Z","steps":["trace[539763396] 'agreement among raft nodes before linearized reading' (duration: 116.615069ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.609811Z","caller":"traceutil/trace.go:171","msg":"trace[116559048] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"114.413447ms","start":"2026-04-14T05:39:22.495377Z","end":"2026-04-14T05:39:22.609791Z","steps":["trace[116559048] 'process raft request' (duration: 114.032289ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.609853Z","caller":"traceutil/trace.go:171","msg":"trace[1344462300] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"136.551443ms","start":"2026-04-14T05:39:22.473193Z","end":"2026-04-14T05:39:22.609744Z","steps":["trace[1344462300] 'process raft request' (duration: 136.113984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:22.610003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.276011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T05:39:22.609869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.733762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:22.610084Z","caller":"traceutil/trace.go:171","msg":"trace[2141367052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:237; }","duration":"170.408593ms","start":"2026-04-14T05:39:22.439658Z","end":"2026-04-14T05:39:22.610067Z","steps":["trace[2141367052] 'agreement among raft nodes before linearized reading' (duration: 170.278641ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:22.610105Z","caller":"traceutil/trace.go:171","msg":"trace[517451173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:237; }","duration":"154.998117ms","start":"2026-04-14T05:39:22.455087Z","end":"2026-04-14T05:39:22.610085Z","steps":["trace[517451173] 'agreement among raft nodes before linearized reading' (duration: 154.729412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:23.935209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.667775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-14T05:39:23.935308Z","caller":"traceutil/trace.go:171","msg":"trace[392023688] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"110.805228ms","start":"2026-04-14T05:39:23.824467Z","end":"2026-04-14T05:39:23.935272Z","steps":["trace[392023688] 'range keys from in-memory index tree' (duration: 110.505933ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.175466Z","caller":"traceutil/trace.go:171","msg":"trace[1667464463] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"101.363636ms","start":"2026-04-14T05:39:27.074081Z","end":"2026-04-14T05:39:27.175444Z","steps":["trace[1667464463] 'read index received' (duration: 101.147342ms)","trace[1667464463] 'applied index is now lower than readState.Index' (duration: 215.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:27.175572Z","caller":"traceutil/trace.go:171","msg":"trace[1792842566] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"142.305683ms","start":"2026-04-14T05:39:27.033255Z","end":"2026-04-14T05:39:27.17556Z","steps":["trace[1792842566] 'process raft request' (duration: 142.011077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.175735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.640282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-14T05:39:27.175778Z","caller":"traceutil/trace.go:171","msg":"trace[1108454438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:301; }","duration":"101.722504ms","start":"2026-04-14T05:39:27.074045Z","end":"2026-04-14T05:39:27.175767Z","steps":["trace[1108454438] 'agreement among raft nodes before linearized reading' (duration: 101.646953ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.365678Z","caller":"traceutil/trace.go:171","msg":"trace[1366442724] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"180.577958ms","start":"2026-04-14T05:39:27.185072Z","end":"2026-04-14T05:39:27.36565Z","steps":["trace[1366442724] 'process raft request' (duration: 175.144643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.659402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.833994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:39:27.659554Z","caller":"traceutil/trace.go:171","msg":"trace[492335772] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"435.195747ms","start":"2026-04-14T05:39:27.22432Z","end":"2026-04-14T05:39:27.659515Z","steps":["trace[492335772] 'read index received' (duration: 135.887689ms)","trace[492335772] 'applied index is now lower than readState.Index' (duration: 299.306428ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:27.659617Z","caller":"traceutil/trace.go:171","msg":"trace[442316338] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"473.847239ms","start":"2026-04-14T05:39:27.185742Z","end":"2026-04-14T05:39:27.65959Z","steps":["trace[442316338] 'process raft request' (duration: 294.695029ms)","trace[442316338] 'compare' (duration: 178.559119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:27.659745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.415871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-14T05:39:27.659792Z","caller":"traceutil/trace.go:171","msg":"trace[680762856] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:303; }","duration":"435.496103ms","start":"2026-04-14T05:39:27.224283Z","end":"2026-04-14T05:39:27.659779Z","steps":["trace[680762856] 'agreement among raft nodes before linearized reading' (duration: 435.36599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.659836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:27.22427Z","time spent":"435.555604ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-14T05:39:27.659855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:27.185716Z","time spent":"474.073053ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:27.660085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.109806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-14T05:39:27.660204Z","caller":"traceutil/trace.go:171","msg":"trace[1681365407] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"385.276668ms","start":"2026-04-14T05:39:27.274896Z","end":"2026-04-14T05:39:27.660173Z","steps":["trace[1681365407] 'agreement among raft nodes before linearized reading' (duration: 385.061654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.660268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:27.27488Z","time spent":"385.373962ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-14T05:39:27.660451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.138987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T05:39:27.660521Z","caller":"traceutil/trace.go:171","msg":"trace[776419726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:303; }","duration":"184.257239ms","start":"2026-04-14T05:39:27.476243Z","end":"2026-04-14T05:39:27.660501Z","steps":["trace[776419726] 'agreement among raft nodes before linearized reading' (duration: 184.132217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.660534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.422541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-14T05:39:27.660575Z","caller":"traceutil/trace.go:171","msg":"trace[30266516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"285.491642ms","start":"2026-04-14T05:39:27.375072Z","end":"2026-04-14T05:39:27.660564Z","steps":["trace[30266516] 'agreement among raft nodes before linearized reading' (duration: 285.427871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.66062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.579455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-14T05:39:27.660682Z","caller":"traceutil/trace.go:171","msg":"trace[1329318308] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"334.676456ms","start":"2026-04-14T05:39:27.32599Z","end":"2026-04-14T05:39:27.660666Z","steps":["trace[1329318308] 'agreement among raft nodes before linearized reading' (duration: 334.574964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.660748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:27.325966Z","time spent":"334.768999ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-14T05:39:27.660726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.538103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-14T05:39:27.660819Z","caller":"traceutil/trace.go:171","msg":"trace[1061115712] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"185.698137ms","start":"2026-04-14T05:39:27.475098Z","end":"2026-04-14T05:39:27.660796Z","steps":["trace[1061115712] 'agreement among raft nodes before linearized reading' (duration: 185.569884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.660771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.597374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-14T05:39:27.660937Z","caller":"traceutil/trace.go:171","msg":"trace[1439523010] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:303; }","duration":"235.785788ms","start":"2026-04-14T05:39:27.425133Z","end":"2026-04-14T05:39:27.660919Z","steps":["trace[1439523010] 'agreement among raft nodes before linearized reading' (duration: 235.601694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:27.661319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.47784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-14T05:39:27.661368Z","caller":"traceutil/trace.go:171","msg":"trace[133918399] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:303; }","duration":"136.572143ms","start":"2026-04-14T05:39:27.524783Z","end":"2026-04-14T05:39:27.661355Z","steps":["trace[133918399] 'agreement among raft nodes before linearized reading' (duration: 135.662235ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.821275Z","caller":"traceutil/trace.go:171","msg":"trace[1068688278] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"154.981586ms","start":"2026-04-14T05:39:27.666258Z","end":"2026-04-14T05:39:27.82124Z","steps":["trace[1068688278] 'process raft request' (duration: 145.780569ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.821371Z","caller":"traceutil/trace.go:171","msg":"trace[1702646489] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"153.548919ms","start":"2026-04-14T05:39:27.667799Z","end":"2026-04-14T05:39:27.821348Z","steps":["trace[1702646489] 'process raft request' (duration: 153.426727ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.821382Z","caller":"traceutil/trace.go:171","msg":"trace[551934249] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"152.724403ms","start":"2026-04-14T05:39:27.668635Z","end":"2026-04-14T05:39:27.82136Z","steps":["trace[551934249] 'process raft request' (duration: 152.635102ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.821502Z","caller":"traceutil/trace.go:171","msg":"trace[1090292989] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"153.719743ms","start":"2026-04-14T05:39:27.667608Z","end":"2026-04-14T05:39:27.821328Z","steps":["trace[1090292989] 'process raft request' (duration: 153.56119ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.821968Z","caller":"traceutil/trace.go:171","msg":"trace[1611316000] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"152.326115ms","start":"2026-04-14T05:39:27.669621Z","end":"2026-04-14T05:39:27.821947Z","steps":["trace[1611316000] 'process raft request' (duration: 151.702394ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.822271Z","caller":"traceutil/trace.go:171","msg":"trace[2059983488] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"152.414976ms","start":"2026-04-14T05:39:27.669842Z","end":"2026-04-14T05:39:27.822257Z","steps":["trace[2059983488] 'process raft request' (duration: 152.056269ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.822511Z","caller":"traceutil/trace.go:171","msg":"trace[1528272193] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:313; }","duration":"149.184885ms","start":"2026-04-14T05:39:27.673312Z","end":"2026-04-14T05:39:27.822496Z","steps":["trace[1528272193] 'read index received' (duration: 11.897889ms)","trace[1528272193] 'applied index is now lower than readState.Index' (duration: 137.286116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:27.822611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.275497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-14T05:39:27.82395Z","caller":"traceutil/trace.go:171","msg":"trace[770106168] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:312; }","duration":"150.659744ms","start":"2026-04-14T05:39:27.673272Z","end":"2026-04-14T05:39:27.823932Z","steps":["trace[770106168] 'agreement among raft nodes before linearized reading' (duration: 149.267927ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.822736Z","caller":"traceutil/trace.go:171","msg":"trace[171472608] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"152.693403ms","start":"2026-04-14T05:39:27.670032Z","end":"2026-04-14T05:39:27.822726Z","steps":["trace[171472608] 'process raft request' (duration: 152.152012ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:27.822769Z","caller":"traceutil/trace.go:171","msg":"trace[920308446] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"152.627791ms","start":"2026-04-14T05:39:27.670134Z","end":"2026-04-14T05:39:27.822762Z","steps":["trace[920308446] 'process raft request' (duration: 152.308595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:28.448345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.933955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:39:28.448496Z","caller":"traceutil/trace.go:171","msg":"trace[12976510] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"430.147311ms","start":"2026-04-14T05:39:28.018286Z","end":"2026-04-14T05:39:28.448434Z","steps":["trace[12976510] 'process raft request' (duration: 87.036622ms)","trace[12976510] 'compare' (duration: 342.67308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:28.448605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.018273Z","time spent":"430.291643ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:28.522897Z","caller":"traceutil/trace.go:171","msg":"trace[1302696481] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:348; }","duration":"415.731383ms","start":"2026-04-14T05:39:28.107135Z","end":"2026-04-14T05:39:28.522867Z","steps":["trace[1302696481] 'read index received' (duration: 415.494189ms)","trace[1302696481] 'applied index is now lower than readState.Index' (duration: 236.094µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:28.523021Z","caller":"traceutil/trace.go:171","msg":"trace[931464982] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"491.272664ms","start":"2026-04-14T05:39:28.031739Z","end":"2026-04-14T05:39:28.523011Z","steps":["trace[931464982] 'process raft request' (duration: 490.843116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:28.52313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.031717Z","time spent":"491.329696ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:28.523453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.290143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-14T05:39:28.523532Z","caller":"traceutil/trace.go:171","msg":"trace[645546142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:340; }","duration":"416.440126ms","start":"2026-04-14T05:39:28.107073Z","end":"2026-04-14T05:39:28.523513Z","steps":["trace[645546142] 'agreement among raft nodes before linearized reading' (duration: 416.058849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:28.523567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.107043Z","time spent":"416.519178ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-04-14T05:39:28.523821Z","caller":"traceutil/trace.go:171","msg":"trace[1826704384] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"491.09924ms","start":"2026-04-14T05:39:28.032667Z","end":"2026-04-14T05:39:28.523767Z","steps":["trace[1826704384] 'process raft request' (duration: 490.125792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:28.524013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.032656Z","time spent":"491.251223ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:39:29.024017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764707092569618,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T05:39:29.127539Z","caller":"traceutil/trace.go:171","msg":"trace[605887634] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"669.217561ms","start":"2026-04-14T05:39:28.458299Z","end":"2026-04-14T05:39:29.127517Z","steps":["trace[605887634] 'process raft request' (duration: 669.151879ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:29.127606Z","caller":"traceutil/trace.go:171","msg":"trace[1056357933] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"673.000354ms","start":"2026-04-14T05:39:28.454592Z","end":"2026-04-14T05:39:29.127592Z","steps":["trace[1056357933] 'process raft request' (duration: 665.060041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.127659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.454568Z","time spent":"673.064835ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:29.127816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.458285Z","time spent":"669.319173ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:29.286378Z","caller":"traceutil/trace.go:171","msg":"trace[394333725] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"754.918536ms","start":"2026-04-14T05:39:28.531392Z","end":"2026-04-14T05:39:29.28631Z","steps":["trace[394333725] 'process raft request' (duration: 730.291024ms)","trace[394333725] 'compare' (duration: 24.446239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:29.286555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.531378Z","time spent":"755.083879ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:29.286682Z","caller":"traceutil/trace.go:171","msg":"trace[1514672102] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"625.49915ms","start":"2026-04-14T05:39:28.661173Z","end":"2026-04-14T05:39:29.286672Z","steps":["trace[1514672102] 'process raft request' (duration: 625.087373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.286751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.661153Z","time spent":"625.568772ms","remote":"127.0.0.1:45504","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-14T05:39:29.286921Z","caller":"traceutil/trace.go:171","msg":"trace[748769242] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:350; }","duration":"763.839888ms","start":"2026-04-14T05:39:28.522974Z","end":"2026-04-14T05:39:29.286814Z","steps":["trace[748769242] 'read index received' (duration: 596.622887ms)","trace[748769242] 'applied index is now lower than readState.Index' (duration: 167.211531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:29.287038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.355908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:39:29.287116Z","caller":"traceutil/trace.go:171","msg":"trace[1201286203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:344; }","duration":"877.481951ms","start":"2026-04-14T05:39:28.409614Z","end":"2026-04-14T05:39:29.287096Z","steps":["trace[1201286203] 'agreement among raft nodes before linearized reading' (duration: 877.331198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.287172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.697657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-vhwrf\" ","response":"range_response_count:1 size:4466"} {"level":"warn","ts":"2026-04-14T05:39:29.287175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.409595Z","time spent":"877.566082ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:39:29.287229Z","caller":"traceutil/trace.go:171","msg":"trace[1936265120] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-vhwrf; range_end:; response_count:1; response_revision:344; }","duration":"761.813309ms","start":"2026-04-14T05:39:28.525399Z","end":"2026-04-14T05:39:29.287213Z","steps":["trace[1936265120] 'agreement among raft nodes before linearized reading' (duration: 761.684887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.287253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.786034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-14T05:39:29.287277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.525376Z","time spent":"761.88902ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4489,"request content":"key:\"/registry/pods/kube-system/kube-proxy-vhwrf\" "} {"level":"info","ts":"2026-04-14T05:39:29.287302Z","caller":"traceutil/trace.go:171","msg":"trace[1623248644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:344; }","duration":"631.050129ms","start":"2026-04-14T05:39:28.65624Z","end":"2026-04-14T05:39:29.28729Z","steps":["trace[1623248644] 'agreement among raft nodes before linearized reading' (duration: 630.968707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.287293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.685144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T05:39:29.287335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.656161Z","time spent":"631.16697ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T05:39:29.287354Z","caller":"traceutil/trace.go:171","msg":"trace[265698353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:344; }","duration":"627.765115ms","start":"2026-04-14T05:39:28.659569Z","end":"2026-04-14T05:39:29.287334Z","steps":["trace[265698353] 'agreement among raft nodes before linearized reading' (duration: 627.540241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:29.287588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:28.659557Z","time spent":"628.00731ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T05:39:31.586195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.435633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:39:31.586364Z","caller":"traceutil/trace.go:171","msg":"trace[417505001] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"282.572586ms","start":"2026-04-14T05:39:31.303759Z","end":"2026-04-14T05:39:31.586332Z","steps":["trace[417505001] 'process raft request' (duration: 282.523756ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:31.586365Z","caller":"traceutil/trace.go:171","msg":"trace[1474455694] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"282.615557ms","start":"2026-04-14T05:39:31.303712Z","end":"2026-04-14T05:39:31.586327Z","steps":["trace[1474455694] 'process raft request' (duration: 175.96825ms)","trace[1474455694] 'compare' (duration: 106.296681ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:34.278707Z","caller":"traceutil/trace.go:171","msg":"trace[271389982] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"317.801893ms","start":"2026-04-14T05:39:33.960886Z","end":"2026-04-14T05:39:34.278688Z","steps":["trace[271389982] 'process raft request' (duration: 317.682781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:34.278831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:33.96086Z","time spent":"317.907695ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:39:35.843967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400589157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:35.844056Z","caller":"traceutil/trace.go:171","msg":"trace[1533127483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"1.400733809s","start":"2026-04-14T05:39:34.443302Z","end":"2026-04-14T05:39:35.844036Z","steps":["trace[1533127483] 'range keys from in-memory index tree' (duration: 1.400493584s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:35.844119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:34.443284Z","time spent":"1.40082633s","remote":"127.0.0.1:45230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:39:36.044023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.144625ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T05:39:36.044125Z","caller":"traceutil/trace.go:171","msg":"trace[2036219157] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"746.366303ms","start":"2026-04-14T05:39:35.297742Z","end":"2026-04-14T05:39:36.044108Z","steps":["trace[2036219157] 'read index received' (duration: 196.136317ms)","trace[2036219157] 'applied index is now lower than readState.Index' (duration: 550.228646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:36.044264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.521066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-14T05:39:36.044297Z","caller":"traceutil/trace.go:171","msg":"trace[515698802] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:374; }","duration":"746.598057ms","start":"2026-04-14T05:39:35.29769Z","end":"2026-04-14T05:39:36.044288Z","steps":["trace[515698802] 'agreement among raft nodes before linearized reading' (duration: 746.513336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.044331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:35.297668Z","time spent":"746.656098ms","remote":"127.0.0.1:45672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-14T05:39:36.044671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.045238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:36.044709Z","caller":"traceutil/trace.go:171","msg":"trace[1889552127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"192.125369ms","start":"2026-04-14T05:39:35.852574Z","end":"2026-04-14T05:39:36.0447Z","steps":["trace[1889552127] 'agreement among raft nodes before linearized reading' (duration: 192.071858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.044851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.068234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T05:39:36.044907Z","caller":"traceutil/trace.go:171","msg":"trace[183162655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"410.133355ms","start":"2026-04-14T05:39:35.63476Z","end":"2026-04-14T05:39:36.044894Z","steps":["trace[183162655] 'agreement among raft nodes before linearized reading' (duration: 410.021793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.044885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.129672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T05:39:36.044941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:35.634695Z","time spent":"410.238138ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T05:39:36.044986Z","caller":"traceutil/trace.go:171","msg":"trace[1354897817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"188.291106ms","start":"2026-04-14T05:39:35.856676Z","end":"2026-04-14T05:39:36.044967Z","steps":["trace[1354897817] 'agreement among raft nodes before linearized reading' (duration: 188.026311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.044883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.584955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:39:36.045137Z","caller":"traceutil/trace.go:171","msg":"trace[638729812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"395.849461ms","start":"2026-04-14T05:39:35.649255Z","end":"2026-04-14T05:39:36.045104Z","steps":["trace[638729812] 'agreement among raft nodes before linearized reading' (duration: 395.492355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.045249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:35.649223Z","time spent":"396.003804ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:39:36.044847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.859806ms","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-14T05:39:36.04547Z","caller":"traceutil/trace.go:171","msg":"trace[622966466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"667.688902ms","start":"2026-04-14T05:39:35.377762Z","end":"2026-04-14T05:39:36.045451Z","steps":["trace[622966466] 'agreement among raft nodes before linearized reading' (duration: 666.793244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.045536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:35.377734Z","time spent":"667.784664ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T05:39:36.551975Z","caller":"traceutil/trace.go:171","msg":"trace[475232533] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"502.417667ms","start":"2026-04-14T05:39:36.049539Z","end":"2026-04-14T05:39:36.551957Z","steps":["trace[475232533] 'process raft request' (duration: 502.295645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:36.552324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:36.049513Z","time spent":"502.740065ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:39:37.163598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.623271ms","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-14T05:39:37.163745Z","caller":"traceutil/trace.go:171","msg":"trace[1274825428] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"477.790414ms","start":"2026-04-14T05:39:36.685936Z","end":"2026-04-14T05:39:37.163727Z","steps":["trace[1274825428] 'range keys from in-memory index tree' (duration: 477.598871ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:37.196274Z","caller":"traceutil/trace.go:171","msg":"trace[645345372] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.146558618s","start":"2026-04-14T05:39:36.049697Z","end":"2026-04-14T05:39:37.196256Z","steps":["trace[645345372] 'process raft request' (duration: 1.146263311s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:37.196455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:36.049688Z","time spent":"1.146647809s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:37.196679Z","caller":"traceutil/trace.go:171","msg":"trace[163382655] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.146835053s","start":"2026-04-14T05:39:36.04982Z","end":"2026-04-14T05:39:37.196655Z","steps":["trace[163382655] 'process raft request' (duration: 1.146370824s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:37.196904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:36.049776Z","time spent":"1.146966575s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:37.196965Z","caller":"traceutil/trace.go:171","msg":"trace[481204483] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"1.14610342s","start":"2026-04-14T05:39:36.05085Z","end":"2026-04-14T05:39:37.196954Z","steps":["trace[481204483] 'process raft request' (duration: 1.145729553s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:37.197522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:36.050832Z","time spent":"1.146635019s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:37.197021Z","caller":"traceutil/trace.go:171","msg":"trace[1834766928] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.140090433s","start":"2026-04-14T05:39:36.056915Z","end":"2026-04-14T05:39:37.197005Z","steps":["trace[1834766928] 'process raft request' (duration: 1.140002001s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:37.197818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:36.05689Z","time spent":"1.140872639s","remote":"127.0.0.1:45672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:40.153505Z","caller":"traceutil/trace.go:171","msg":"trace[1564355544] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"119.107047ms","start":"2026-04-14T05:39:40.034364Z","end":"2026-04-14T05:39:40.153471Z","steps":["trace[1564355544] 'process raft request' (duration: 62.523699ms)","trace[1564355544] 'compare' (duration: 56.446635ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:41.377265Z","caller":"traceutil/trace.go:171","msg":"trace[259412144] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"156.836372ms","start":"2026-04-14T05:39:41.220397Z","end":"2026-04-14T05:39:41.377234Z","steps":["trace[259412144] 'process raft request' (duration: 155.429015ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:42.064153Z","caller":"traceutil/trace.go:171","msg":"trace[109499351] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"177.807855ms","start":"2026-04-14T05:39:41.886321Z","end":"2026-04-14T05:39:42.064129Z","steps":["trace[109499351] 'process raft request' (duration: 177.685722ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:47.421913Z","caller":"traceutil/trace.go:171","msg":"trace[47132385] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"122.944161ms","start":"2026-04-14T05:39:47.29892Z","end":"2026-04-14T05:39:47.421864Z","steps":["trace[47132385] 'process raft request' (duration: 121.340241ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:49.446208Z","caller":"traceutil/trace.go:171","msg":"trace[748447487] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"147.779089ms","start":"2026-04-14T05:39:49.298409Z","end":"2026-04-14T05:39:49.446188Z","steps":["trace[748447487] 'read index received' (duration: 147.086065ms)","trace[748447487] 'applied index is now lower than readState.Index' (duration: 692.074µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:49.446285Z","caller":"traceutil/trace.go:171","msg":"trace[834113976] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"167.279353ms","start":"2026-04-14T05:39:49.278968Z","end":"2026-04-14T05:39:49.446247Z","steps":["trace[834113976] 'process raft request' (duration: 166.858304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:49.446493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.071984ms","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-14T05:39:49.446807Z","caller":"traceutil/trace.go:171","msg":"trace[357368226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:432; }","duration":"148.40901ms","start":"2026-04-14T05:39:49.298368Z","end":"2026-04-14T05:39:49.446777Z","steps":["trace[357368226] 'agreement among raft nodes before linearized reading' (duration: 147.964272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.942483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764707092569877,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T05:39:51.974687Z","caller":"traceutil/trace.go:171","msg":"trace[1687790770] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"763.92248ms","start":"2026-04-14T05:39:51.210727Z","end":"2026-04-14T05:39:51.974649Z","steps":["trace[1687790770] 'process raft request' (duration: 763.791337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.975287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.210703Z","time spent":"764.071262ms","remote":"127.0.0.1:45252","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-14T05:39:51.988014Z","caller":"traceutil/trace.go:171","msg":"trace[87431797] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"774.165456ms","start":"2026-04-14T05:39:51.213831Z","end":"2026-04-14T05:39:51.987997Z","steps":["trace[87431797] 'process raft request' (duration: 774.017043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.213813Z","time spent":"774.256478ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:39:51.988141Z","caller":"traceutil/trace.go:171","msg":"trace[263030427] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"765.605092ms","start":"2026-04-14T05:39:51.222477Z","end":"2026-04-14T05:39:51.988082Z","steps":["trace[263030427] 'process raft request' (duration: 765.479429ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:51.988229Z","caller":"traceutil/trace.go:171","msg":"trace[1589790469] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"763.050383ms","start":"2026-04-14T05:39:51.225173Z","end":"2026-04-14T05:39:51.988224Z","steps":["trace[1589790469] 'process raft request' (duration: 762.827039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.225168Z","time spent":"763.089294ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:39:51.988272Z","caller":"traceutil/trace.go:171","msg":"trace[1343496348] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"763.661974ms","start":"2026-04-14T05:39:51.224567Z","end":"2026-04-14T05:39:51.988229Z","steps":["trace[1343496348] 'process raft request' (duration: 763.40935ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:51.988318Z","caller":"traceutil/trace.go:171","msg":"trace[1503209685] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:489; }","duration":"546.252139ms","start":"2026-04-14T05:39:51.44204Z","end":"2026-04-14T05:39:51.988292Z","steps":["trace[1503209685] 'read index received' (duration: 532.814652ms)","trace[1503209685] 'applied index is now lower than readState.Index' (duration: 13.436987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:51.988351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.224549Z","time spent":"763.762436ms","remote":"127.0.0.1:45252","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-14T05:39:51.988364Z","caller":"traceutil/trace.go:171","msg":"trace[510561638] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"774.478782ms","start":"2026-04-14T05:39:51.213879Z","end":"2026-04-14T05:39:51.988358Z","steps":["trace[510561638] 'process raft request' (duration: 774.056444ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:51.988384Z","caller":"traceutil/trace.go:171","msg":"trace[1877766668] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"738.381739ms","start":"2026-04-14T05:39:51.249996Z","end":"2026-04-14T05:39:51.988378Z","steps":["trace[1877766668] 'process raft request' (duration: 738.100304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.213876Z","time spent":"774.510452ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:51.988421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.249978Z","time spent":"738.421619ms","remote":"127.0.0.1:45252","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-14T05:39:51.988361Z","caller":"traceutil/trace.go:171","msg":"trace[60800299] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"762.190676ms","start":"2026-04-14T05:39:51.226164Z","end":"2026-04-14T05:39:51.988355Z","steps":["trace[60800299] 'process raft request' (duration: 761.90249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:51.98842Z","caller":"traceutil/trace.go:171","msg":"trace[950711977] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"713.631344ms","start":"2026-04-14T05:39:51.274772Z","end":"2026-04-14T05:39:51.988403Z","steps":["trace[950711977] 'process raft request' (duration: 713.398389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.226158Z","time spent":"762.283617ms","remote":"127.0.0.1:45252","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-14T05:39:51.988462Z","caller":"traceutil/trace.go:171","msg":"trace[1922491572] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"738.381439ms","start":"2026-04-14T05:39:51.250073Z","end":"2026-04-14T05:39:51.988455Z","steps":["trace[1922491572] 'process raft request' (duration: 738.058033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.274746Z","time spent":"713.711145ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:51.988348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.222451Z","time spent":"765.804496ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:39:51.988513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.479464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:51.988469Z","caller":"traceutil/trace.go:171","msg":"trace[1577626881] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"595.776721ms","start":"2026-04-14T05:39:51.392686Z","end":"2026-04-14T05:39:51.988462Z","steps":["trace[1577626881] 'process raft request' (duration: 595.531456ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:51.988534Z","caller":"traceutil/trace.go:171","msg":"trace[285363030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"546.537485ms","start":"2026-04-14T05:39:51.441991Z","end":"2026-04-14T05:39:51.988529Z","steps":["trace[285363030] 'agreement among raft nodes before linearized reading' (duration: 546.486234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.441971Z","time spent":"546.578206ms","remote":"127.0.0.1:45230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:39:51.988591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.392666Z","time spent":"595.873342ms","remote":"127.0.0.1:45252","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-14T05:39:51.988207Z","caller":"traceutil/trace.go:171","msg":"trace[1739812193] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"762.759988ms","start":"2026-04-14T05:39:51.225438Z","end":"2026-04-14T05:39:51.988198Z","steps":["trace[1739812193] 'process raft request' (duration: 762.601445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.988665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.225403Z","time spent":"763.249247ms","remote":"127.0.0.1:45252","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-14T05:39:51.988496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.250069Z","time spent":"738.414369ms","remote":"127.0.0.1:45252","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-14T05:39:51.997907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.80299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T05:39:51.997987Z","caller":"traceutil/trace.go:171","msg":"trace[174014674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"535.934831ms","start":"2026-04-14T05:39:51.462035Z","end":"2026-04-14T05:39:51.99797Z","steps":["trace[174014674] 'agreement among raft nodes before linearized reading' (duration: 535.787209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.998002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.078187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T05:39:51.998022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.462013Z","time spent":"536.000863ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T05:39:51.99805Z","caller":"traceutil/trace.go:171","msg":"trace[1790594942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"533.159729ms","start":"2026-04-14T05:39:51.464878Z","end":"2026-04-14T05:39:51.998038Z","steps":["trace[1790594942] 'agreement among raft nodes before linearized reading' (duration: 533.036166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.998078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.464867Z","time spent":"533.206029ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T05:39:51.998817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.748349ms","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-14T05:39:51.998851Z","caller":"traceutil/trace.go:171","msg":"trace[342006132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"533.783819ms","start":"2026-04-14T05:39:51.46506Z","end":"2026-04-14T05:39:51.998844Z","steps":["trace[342006132] 'agreement among raft nodes before linearized reading' (duration: 533.720008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:51.99887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:51.465036Z","time spent":"533.830041ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T05:39:52.747573Z","caller":"traceutil/trace.go:171","msg":"trace[1115940041] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:546; }","duration":"303.929707ms","start":"2026-04-14T05:39:52.44362Z","end":"2026-04-14T05:39:52.74755Z","steps":["trace[1115940041] 'read index received' (duration: 294.254641ms)","trace[1115940041] 'applied index is now lower than readState.Index' (duration: 9.674056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:52.747741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.09422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:39:52.747778Z","caller":"traceutil/trace.go:171","msg":"trace[1290654173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"304.191302ms","start":"2026-04-14T05:39:52.443576Z","end":"2026-04-14T05:39:52.747768Z","steps":["trace[1290654173] 'agreement among raft nodes before linearized reading' (duration: 304.07654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:52.747819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:52.443562Z","time spent":"304.247033ms","remote":"127.0.0.1:45222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:39:52.748072Z","caller":"traceutil/trace.go:171","msg":"trace[627150310] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"400.287657ms","start":"2026-04-14T05:39:52.347774Z","end":"2026-04-14T05:39:52.748061Z","steps":["trace[627150310] 'process raft request' (duration: 389.996579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:52.748166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:52.347744Z","time spent":"400.355619ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:52.748165Z","caller":"traceutil/trace.go:171","msg":"trace[676203575] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"369.428075ms","start":"2026-04-14T05:39:52.378691Z","end":"2026-04-14T05:39:52.748119Z","steps":["trace[676203575] 'process raft request' (duration: 368.746481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:52.748383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:52.378662Z","time spent":"369.622608ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:52.748529Z","caller":"traceutil/trace.go:171","msg":"trace[1646307897] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"395.120977ms","start":"2026-04-14T05:39:52.353396Z","end":"2026-04-14T05:39:52.748517Z","steps":["trace[1646307897] 'process raft request' (duration: 393.965985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:52.74858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:52.353359Z","time spent":"395.196879ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:39:55.061061Z","caller":"traceutil/trace.go:171","msg":"trace[2038163394] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"105.368333ms","start":"2026-04-14T05:39:54.955668Z","end":"2026-04-14T05:39:55.061036Z","steps":["trace[2038163394] 'process raft request' (duration: 105.256231ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:55.08873Z","caller":"traceutil/trace.go:171","msg":"trace[952000188] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"132.842072ms","start":"2026-04-14T05:39:54.955868Z","end":"2026-04-14T05:39:55.08871Z","steps":["trace[952000188] 'process raft request' (duration: 132.773941ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:58.003771Z","caller":"traceutil/trace.go:171","msg":"trace[640968589] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"130.438466ms","start":"2026-04-14T05:39:57.873305Z","end":"2026-04-14T05:39:58.003743Z","steps":["trace[640968589] 'process raft request' (duration: 129.859464ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:58.854937Z","caller":"traceutil/trace.go:171","msg":"trace[2081290956] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"262.339819ms","start":"2026-04-14T05:39:58.59257Z","end":"2026-04-14T05:39:58.854909Z","steps":["trace[2081290956] 'read index received' (duration: 261.479422ms)","trace[2081290956] 'applied index is now lower than readState.Index' (duration: 859.527µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:39:58.855226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.642494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lhp8g\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T05:39:58.855265Z","caller":"traceutil/trace.go:171","msg":"trace[1669503244] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lhp8g; range_end:; response_count:1; response_revision:581; }","duration":"262.737666ms","start":"2026-04-14T05:39:58.592515Z","end":"2026-04-14T05:39:58.855253Z","steps":["trace[1669503244] 'agreement among raft nodes before linearized reading' (duration: 262.493742ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:39:58.85556Z","caller":"traceutil/trace.go:171","msg":"trace[885375249] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"337.767866ms","start":"2026-04-14T05:39:58.517754Z","end":"2026-04-14T05:39:58.855522Z","steps":["trace[885375249] 'process raft request' (duration: 336.557013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:58.855773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:58.517728Z","time spent":"337.919018ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036385,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:59.390161Z","caller":"traceutil/trace.go:171","msg":"trace[1759574699] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"521.452054ms","start":"2026-04-14T05:39:58.868681Z","end":"2026-04-14T05:39:59.390133Z","steps":["trace[1759574699] 'process raft request' (duration: 521.296121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:59.390313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:58.868661Z","time spent":"521.576626ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:39:59.51992Z","caller":"traceutil/trace.go:171","msg":"trace[2134463654] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"636.3844ms","start":"2026-04-14T05:39:58.883476Z","end":"2026-04-14T05:39:59.51986Z","steps":["trace[2134463654] 'process raft request' (duration: 616.859065ms)","trace[2134463654] 'compare' (duration: 19.331721ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:39:59.519962Z","caller":"traceutil/trace.go:171","msg":"trace[2068767601] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"602.184854ms","start":"2026-04-14T05:39:58.917716Z","end":"2026-04-14T05:39:59.519901Z","steps":["trace[2068767601] 'process raft request' (duration: 602.050091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:39:59.520233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:58.88344Z","time spent":"636.596445ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:39:59.520429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:58.917681Z","time spent":"602.564972ms","remote":"127.0.0.1:45734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:40:00.489763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764707092570120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T05:40:00.990964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764707092570120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T05:40:01.590179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.409451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:40:01.590285Z","caller":"traceutil/trace.go:171","msg":"trace[1182020843] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.647773342s","start":"2026-04-14T05:39:59.942474Z","end":"2026-04-14T05:40:01.590247Z","steps":["trace[1182020843] 'process raft request' (duration: 1.363221558s)","trace[1182020843] 'compare' (duration: 283.993144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:01.590325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:59.942457Z","time spent":"1.647848824s","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036405,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:40:01.6011Z","caller":"traceutil/trace.go:171","msg":"trace[530437016] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"1.611360243s","start":"2026-04-14T05:39:59.98962Z","end":"2026-04-14T05:40:01.60098Z","steps":["trace[530437016] 'read index received' (duration: 1.315690346s)","trace[530437016] 'applied index is now lower than readState.Index' (duration: 295.668707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:01.601393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500409453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T05:40:01.601498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157801754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:40:01.601498Z","caller":"traceutil/trace.go:171","msg":"trace[250595670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.500610996s","start":"2026-04-14T05:40:00.100867Z","end":"2026-04-14T05:40:01.601478Z","steps":["trace[250595670] 'agreement among raft nodes before linearized reading' (duration: 1.500359632s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.601447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.744141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:40:01.601568Z","caller":"traceutil/trace.go:171","msg":"trace[2012713616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"206.898583ms","start":"2026-04-14T05:40:01.394662Z","end":"2026-04-14T05:40:01.601561Z","steps":["trace[2012713616] 'agreement among raft nodes before linearized reading' (duration: 206.71712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.601582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483160221s","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-14T05:40:01.601606Z","caller":"traceutil/trace.go:171","msg":"trace[1545316533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.483205472s","start":"2026-04-14T05:40:00.118393Z","end":"2026-04-14T05:40:01.601598Z","steps":["trace[1545316533] 'agreement among raft nodes before linearized reading' (duration: 1.483141171s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.601633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:00.118376Z","time spent":"1.483252672s","remote":"127.0.0.1:45504","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-14T05:40:01.601748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487955602s","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-14T05:40:01.601768Z","caller":"traceutil/trace.go:171","msg":"trace[1978141221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"1.488018064s","start":"2026-04-14T05:40:00.113744Z","end":"2026-04-14T05:40:01.601762Z","steps":["trace[1978141221] 'agreement among raft nodes before linearized reading' (duration: 1.487982453s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.601783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:00.113712Z","time spent":"1.488067204s","remote":"127.0.0.1:45504","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-14T05:40:01.601528Z","caller":"traceutil/trace.go:171","msg":"trace[315382432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.157875485s","start":"2026-04-14T05:40:00.44364Z","end":"2026-04-14T05:40:01.601516Z","steps":["trace[315382432] 'agreement among raft nodes before linearized reading' (duration: 1.157830694s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.60192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:00.443606Z","time spent":"1.158308623s","remote":"127.0.0.1:45222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:40:01.602151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:00.100832Z","time spent":"1.50131039s","remote":"127.0.0.1:45504","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":"info","ts":"2026-04-14T05:40:01.602427Z","caller":"traceutil/trace.go:171","msg":"trace[409116749] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.376526303s","start":"2026-04-14T05:40:00.225891Z","end":"2026-04-14T05:40:01.602418Z","steps":["trace[409116749] 'process raft request' (duration: 1.374870861s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.6025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:00.225835Z","time spent":"1.376612904s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:40:01.605248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.615603584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T05:40:01.605365Z","caller":"traceutil/trace.go:171","msg":"trace[1621291001] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:586; }","duration":"1.615766588s","start":"2026-04-14T05:39:59.989564Z","end":"2026-04-14T05:40:01.605331Z","steps":["trace[1621291001] 'agreement among raft nodes before linearized reading' (duration: 1.611428725s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:01.605431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:39:59.989539Z","time spent":"1.615878739s","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-04-14T05:40:02.197409Z","caller":"traceutil/trace.go:171","msg":"trace[447438002] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"548.276109ms","start":"2026-04-14T05:40:01.649105Z","end":"2026-04-14T05:40:02.197382Z","steps":["trace[447438002] 'process raft request' (duration: 548.223598ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:02.197505Z","caller":"traceutil/trace.go:171","msg":"trace[1023433115] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"555.225283ms","start":"2026-04-14T05:40:01.642228Z","end":"2026-04-14T05:40:02.197453Z","steps":["trace[1023433115] 'process raft request' (duration: 554.940117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:02.19755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:01.649092Z","time spent":"548.393081ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:40:02.19773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:01.6422Z","time spent":"555.412256ms","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:40:02.327713Z","caller":"traceutil/trace.go:171","msg":"trace[966692639] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"122.144096ms","start":"2026-04-14T05:40:02.205553Z","end":"2026-04-14T05:40:02.327698Z","steps":["trace[966692639] 'process raft request' (duration: 120.436973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:02.327968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.711518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-14T05:40:02.328081Z","caller":"traceutil/trace.go:171","msg":"trace[479303478] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:593; }","duration":"120.900991ms","start":"2026-04-14T05:40:02.207156Z","end":"2026-04-14T05:40:02.328057Z","steps":["trace[479303478] 'agreement among raft nodes before linearized reading' (duration: 120.649807ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:02.327629Z","caller":"traceutil/trace.go:171","msg":"trace[1740414506] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"120.375702ms","start":"2026-04-14T05:40:02.207218Z","end":"2026-04-14T05:40:02.327593Z","steps":["trace[1740414506] 'read index received' (duration: 118.623588ms)","trace[1740414506] 'applied index is now lower than readState.Index' (duration: 1.750894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:02.328533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.800922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:40:02.328626Z","caller":"traceutil/trace.go:171","msg":"trace[772145736] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:593; }","duration":"117.969305ms","start":"2026-04-14T05:40:02.210636Z","end":"2026-04-14T05:40:02.328605Z","steps":["trace[772145736] 'agreement among raft nodes before linearized reading' (duration: 117.652789ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:05.194671Z","caller":"traceutil/trace.go:171","msg":"trace[1436283179] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"107.874181ms","start":"2026-04-14T05:40:05.086773Z","end":"2026-04-14T05:40:05.194647Z","steps":["trace[1436283179] 'process raft request' (duration: 107.709748ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:08.681514Z","caller":"traceutil/trace.go:171","msg":"trace[172756313] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"393.935345ms","start":"2026-04-14T05:40:08.287553Z","end":"2026-04-14T05:40:08.681489Z","steps":["trace[172756313] 'read index received' (duration: 300.871458ms)","trace[172756313] 'applied index is now lower than readState.Index' (duration: 93.063007ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:40:08.681608Z","caller":"traceutil/trace.go:171","msg":"trace[678185464] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"436.709066ms","start":"2026-04-14T05:40:08.244859Z","end":"2026-04-14T05:40:08.681568Z","steps":["trace[678185464] 'process raft request' (duration: 343.680279ms)","trace[678185464] 'compare' (duration: 92.706331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:08.681753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:08.244836Z","time spent":"436.841209ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:40:08.681964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.111905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-14T05:40:08.681997Z","caller":"traceutil/trace.go:171","msg":"trace[970675786] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"197.200627ms","start":"2026-04-14T05:40:08.484786Z","end":"2026-04-14T05:40:08.681987Z","steps":["trace[970675786] 'agreement among raft nodes before linearized reading' (duration: 197.061484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:08.682385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.377085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:40:08.68243Z","caller":"traceutil/trace.go:171","msg":"trace[259921490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:678; }","duration":"178.454497ms","start":"2026-04-14T05:40:08.503959Z","end":"2026-04-14T05:40:08.682413Z","steps":["trace[259921490] 'agreement among raft nodes before linearized reading' (duration: 178.353685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:08.682463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.908304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-14T05:40:08.682511Z","caller":"traceutil/trace.go:171","msg":"trace[1441496858] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"395.002206ms","start":"2026-04-14T05:40:08.287497Z","end":"2026-04-14T05:40:08.682499Z","steps":["trace[1441496858] 'agreement among raft nodes before linearized reading' (duration: 394.142929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:08.68255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:08.287473Z","time spent":"395.067048ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-04-14T05:40:08.83536Z","caller":"traceutil/trace.go:171","msg":"trace[401832822] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"146.897951ms","start":"2026-04-14T05:40:08.688446Z","end":"2026-04-14T05:40:08.835344Z","steps":["trace[401832822] 'process raft request' (duration: 145.174297ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:08.896079Z","caller":"traceutil/trace.go:171","msg":"trace[483538880] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"200.568901ms","start":"2026-04-14T05:40:08.695468Z","end":"2026-04-14T05:40:08.896037Z","steps":["trace[483538880] 'process raft request' (duration: 200.151323ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:19.156476Z","caller":"traceutil/trace.go:171","msg":"trace[2111628076] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"263.688482ms","start":"2026-04-14T05:40:18.892762Z","end":"2026-04-14T05:40:19.156451Z","steps":["trace[2111628076] 'read index received' (duration: 263.420797ms)","trace[2111628076] 'applied index is now lower than readState.Index' (duration: 266.545µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:40:19.156626Z","caller":"traceutil/trace.go:171","msg":"trace[884512538] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"428.226843ms","start":"2026-04-14T05:40:18.72835Z","end":"2026-04-14T05:40:19.156577Z","steps":["trace[884512538] 'process raft request' (duration: 427.939718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:19.156851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.035698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:40:19.156903Z","caller":"traceutil/trace.go:171","msg":"trace[2072447947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"264.219583ms","start":"2026-04-14T05:40:18.89267Z","end":"2026-04-14T05:40:19.15689Z","steps":["trace[2072447947] 'agreement among raft nodes before linearized reading' (duration: 263.883326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:19.156905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:18.728322Z","time spent":"428.416866ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2807,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:40:53.723718Z","caller":"traceutil/trace.go:171","msg":"trace[714662334] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"328.088612ms","start":"2026-04-14T05:40:53.395598Z","end":"2026-04-14T05:40:53.723687Z","steps":["trace[714662334] 'process raft request' (duration: 327.879918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:53.723884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:53.395578Z","time spent":"328.224075ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:40:54.030288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.446028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:40:54.030469Z","caller":"traceutil/trace.go:171","msg":"trace[1764318000] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"612.505371ms","start":"2026-04-14T05:40:53.417943Z","end":"2026-04-14T05:40:54.030448Z","steps":["trace[1764318000] 'process raft request' (duration: 347.795598ms)","trace[1764318000] 'compare' (duration: 264.313395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:54.030563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:53.417927Z","time spent":"612.586642ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:40:54.926106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.62614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:40:54.926217Z","caller":"traceutil/trace.go:171","msg":"trace[581844715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"483.791974ms","start":"2026-04-14T05:40:54.442401Z","end":"2026-04-14T05:40:54.926193Z","steps":["trace[581844715] 'range keys from in-memory index tree' (duration: 483.520609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:54.926207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.70117ms","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-14T05:40:54.926294Z","caller":"traceutil/trace.go:171","msg":"trace[851742094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"747.839233ms","start":"2026-04-14T05:40:54.178432Z","end":"2026-04-14T05:40:54.926272Z","steps":["trace[851742094] 'range keys from in-memory index tree' (duration: 747.440154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:54.926371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.178414Z","time spent":"747.918444ms","remote":"127.0.0.1:45504","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-14T05:40:54.926544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.442381Z","time spent":"484.14693ms","remote":"127.0.0.1:45222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:40:54.926653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.954928ms","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-14T05:40:54.926786Z","caller":"traceutil/trace.go:171","msg":"trace[1925200338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"756.149501ms","start":"2026-04-14T05:40:54.170607Z","end":"2026-04-14T05:40:54.926756Z","steps":["trace[1925200338] 'range keys from in-memory index tree' (duration: 755.086951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:54.926836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.170576Z","time spent":"756.250543ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:40:55.467745Z","caller":"traceutil/trace.go:171","msg":"trace[429496417] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"536.243044ms","start":"2026-04-14T05:40:54.931462Z","end":"2026-04-14T05:40:55.467705Z","steps":["trace[429496417] 'process raft request' (duration: 536.050881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:55.467915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.931441Z","time spent":"536.386007ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:40:55.798114Z","caller":"traceutil/trace.go:171","msg":"trace[1176288174] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"665.571903ms","start":"2026-04-14T05:40:55.132517Z","end":"2026-04-14T05:40:55.798089Z","steps":["trace[1176288174] 'process raft request' (duration: 665.338928ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:40:55.79811Z","caller":"traceutil/trace.go:171","msg":"trace[256280135] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"866.530108ms","start":"2026-04-14T05:40:54.931549Z","end":"2026-04-14T05:40:55.798079Z","steps":["trace[256280135] 'process raft request' (duration: 866.04352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:55.798245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:55.132497Z","time spent":"665.675724ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:40:55.798499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.93152Z","time spent":"866.65766ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:40:55.80192Z","caller":"traceutil/trace.go:171","msg":"trace[64067914] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"823.476086ms","start":"2026-04-14T05:40:54.978417Z","end":"2026-04-14T05:40:55.801894Z","steps":["trace[64067914] 'read index received' (duration: 489.146946ms)","trace[64067914] 'applied index is now lower than readState.Index' (duration: 334.32785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:40:55.802032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.616019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:40:55.802076Z","caller":"traceutil/trace.go:171","msg":"trace[1474286834] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:978; }","duration":"823.697491ms","start":"2026-04-14T05:40:54.978369Z","end":"2026-04-14T05:40:55.802067Z","steps":["trace[1474286834] 'agreement among raft nodes before linearized reading' (duration: 823.620089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:55.802104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:54.978329Z","time spent":"823.769291ms","remote":"127.0.0.1:45532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:40:55.802144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.982091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:40:55.802222Z","caller":"traceutil/trace.go:171","msg":"trace[1882095224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"358.118895ms","start":"2026-04-14T05:40:55.444085Z","end":"2026-04-14T05:40:55.802204Z","steps":["trace[1882095224] 'agreement among raft nodes before linearized reading' (duration: 357.988273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:40:55.802271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:40:55.444058Z","time spent":"358.205756ms","remote":"127.0.0.1:45222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:41:06.093839Z","caller":"traceutil/trace.go:171","msg":"trace[754602692] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"216.648446ms","start":"2026-04-14T05:41:05.877164Z","end":"2026-04-14T05:41:06.093813Z","steps":["trace[754602692] 'process raft request' (duration: 216.473633ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:19.973068Z","caller":"traceutil/trace.go:171","msg":"trace[1055364878] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"375.658496ms","start":"2026-04-14T05:41:19.597383Z","end":"2026-04-14T05:41:19.973041Z","steps":["trace[1055364878] 'process raft request' (duration: 375.495992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:19.973342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:19.597346Z","time spent":"375.829189ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:41:20.618756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.232318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:41:20.618886Z","caller":"traceutil/trace.go:171","msg":"trace[1490229273] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1091; }","duration":"652.200703ms","start":"2026-04-14T05:41:19.966665Z","end":"2026-04-14T05:41:20.618866Z","steps":["trace[1490229273] 'read index received' (duration: 6.232765ms)","trace[1490229273] 'applied index is now lower than readState.Index' (duration: 645.966287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:20.619082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.427386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:41:20.619109Z","caller":"traceutil/trace.go:171","msg":"trace[2005399536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"652.513068ms","start":"2026-04-14T05:41:19.966589Z","end":"2026-04-14T05:41:20.619102Z","steps":["trace[2005399536] 'agreement among raft nodes before linearized reading' (duration: 652.380345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:20.619136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:19.966557Z","time spent":"652.573169ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:41:20.619282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.403261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T05:41:20.619308Z","caller":"traceutil/trace.go:171","msg":"trace[517002122] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1055; }","duration":"627.483222ms","start":"2026-04-14T05:41:19.991819Z","end":"2026-04-14T05:41:20.619302Z","steps":["trace[517002122] 'agreement among raft nodes before linearized reading' (duration: 627.416411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:20.619327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:19.991794Z","time spent":"627.528203ms","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-14T05:41:20.61937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.376835ms","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-14T05:41:20.619466Z","caller":"traceutil/trace.go:171","msg":"trace[539485916] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1055; }","duration":"291.534958ms","start":"2026-04-14T05:41:20.327913Z","end":"2026-04-14T05:41:20.619448Z","steps":["trace[539485916] 'agreement among raft nodes before linearized reading' (duration: 291.372845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:20.619635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.955381ms","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-14T05:41:20.619889Z","caller":"traceutil/trace.go:171","msg":"trace[677592180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"418.202185ms","start":"2026-04-14T05:41:20.201639Z","end":"2026-04-14T05:41:20.619841Z","steps":["trace[677592180] 'agreement among raft nodes before linearized reading' (duration: 417.650255ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:20.620083Z","caller":"traceutil/trace.go:171","msg":"trace[559514278] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"667.61815ms","start":"2026-04-14T05:41:19.952446Z","end":"2026-04-14T05:41:20.620065Z","steps":["trace[559514278] 'process raft request' (duration: 378.955855ms)","trace[559514278] 'compare' (duration: 287.095596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:20.620215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:19.952405Z","time spent":"667.731931ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:41:20.619674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.535541ms","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-14T05:41:20.620368Z","caller":"traceutil/trace.go:171","msg":"trace[1140696328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"354.269835ms","start":"2026-04-14T05:41:20.26608Z","end":"2026-04-14T05:41:20.620349Z","steps":["trace[1140696328] 'agreement among raft nodes before linearized reading' (duration: 353.407869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:20.620461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:20.266068Z","time spent":"354.375358ms","remote":"127.0.0.1:45504","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-14T05:41:20.620087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:20.201628Z","time spent":"418.43803ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T05:41:20.619772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.327161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-14T05:41:20.620651Z","caller":"traceutil/trace.go:171","msg":"trace[494418575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"424.238248ms","start":"2026-04-14T05:41:20.196392Z","end":"2026-04-14T05:41:20.62063Z","steps":["trace[494418575] 'agreement among raft nodes before linearized reading' (duration: 423.030075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:20.620708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:20.196367Z","time spent":"424.326899ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T05:41:22.157948Z","caller":"traceutil/trace.go:171","msg":"trace[1313583260] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"174.654802ms","start":"2026-04-14T05:41:21.983272Z","end":"2026-04-14T05:41:22.157927Z","steps":["trace[1313583260] 'process raft request' (duration: 174.53543ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:24.473607Z","caller":"traceutil/trace.go:171","msg":"trace[816744749] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"306.610418ms","start":"2026-04-14T05:41:24.166965Z","end":"2026-04-14T05:41:24.473576Z","steps":["trace[816744749] 'process raft request' (duration: 306.440985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:24.473774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:24.166949Z","time spent":"306.73307ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:41:24.474162Z","caller":"traceutil/trace.go:171","msg":"trace[13813132] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"299.542156ms","start":"2026-04-14T05:41:24.174597Z","end":"2026-04-14T05:41:24.474139Z","steps":["trace[13813132] 'read index received' (duration: 298.689741ms)","trace[13813132] 'applied index is now lower than readState.Index' (duration: 849.545µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:24.474379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.730791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T05:41:24.474439Z","caller":"traceutil/trace.go:171","msg":"trace[281059549] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1068; }","duration":"299.852162ms","start":"2026-04-14T05:41:24.174571Z","end":"2026-04-14T05:41:24.474424Z","steps":["trace[281059549] 'agreement among raft nodes before linearized reading' (duration: 299.7105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:24.859436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.938433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:41:24.85955Z","caller":"traceutil/trace.go:171","msg":"trace[1000140958] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"345.079415ms","start":"2026-04-14T05:41:24.51445Z","end":"2026-04-14T05:41:24.85953Z","steps":["trace[1000140958] 'process raft request' (duration: 43.945668ms)","trace[1000140958] 'compare' (duration: 300.647298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:24.859613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:24.514421Z","time spent":"345.162236ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:41:25.049334Z","caller":"traceutil/trace.go:171","msg":"trace[72285792] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"330.922632ms","start":"2026-04-14T05:41:24.71838Z","end":"2026-04-14T05:41:25.049303Z","steps":["trace[72285792] 'read index received' (duration: 296.172905ms)","trace[72285792] 'applied index is now lower than readState.Index' (duration: 34.748657ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:41:25.049361Z","caller":"traceutil/trace.go:171","msg":"trace[1670607429] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"391.591692ms","start":"2026-04-14T05:41:24.657733Z","end":"2026-04-14T05:41:25.049324Z","steps":["trace[1670607429] 'process raft request' (duration: 356.909206ms)","trace[1670607429] 'compare' (duration: 34.545404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:25.049529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:24.6577Z","time spent":"391.736754ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:41:25.049583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.785533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T05:41:25.049618Z","caller":"traceutil/trace.go:171","msg":"trace[1540429645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"291.859824ms","start":"2026-04-14T05:41:24.757747Z","end":"2026-04-14T05:41:25.049607Z","steps":["trace[1540429645] 'agreement among raft nodes before linearized reading' (duration: 291.700231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:25.049647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.751252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-14T05:41:25.049649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.282298ms","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-14T05:41:25.049695Z","caller":"traceutil/trace.go:171","msg":"trace[213619988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"291.817793ms","start":"2026-04-14T05:41:24.757865Z","end":"2026-04-14T05:41:25.049682Z","steps":["trace[213619988] 'agreement among raft nodes before linearized reading' (duration: 291.63916ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:25.049737Z","caller":"traceutil/trace.go:171","msg":"trace[387219611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"331.422901ms","start":"2026-04-14T05:41:24.71829Z","end":"2026-04-14T05:41:25.049713Z","steps":["trace[387219611] 'agreement among raft nodes before linearized reading' (duration: 331.123356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:25.049775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:24.718271Z","time spent":"331.497232ms","remote":"127.0.0.1:45504","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-14T05:41:25.049788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.908115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:41:25.049815Z","caller":"traceutil/trace.go:171","msg":"trace[1701903316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"291.934296ms","start":"2026-04-14T05:41:24.757873Z","end":"2026-04-14T05:41:25.049807Z","steps":["trace[1701903316] 'agreement among raft nodes before linearized reading' (duration: 291.850914ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:33.197423Z","caller":"traceutil/trace.go:171","msg":"trace[2057707635] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"101.594741ms","start":"2026-04-14T05:41:33.095804Z","end":"2026-04-14T05:41:33.197399Z","steps":["trace[2057707635] 'read index received' (duration: 101.374047ms)","trace[2057707635] 'applied index is now lower than readState.Index' (duration: 219.704µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:41:33.197463Z","caller":"traceutil/trace.go:171","msg":"trace[390058130] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"105.292501ms","start":"2026-04-14T05:41:33.092139Z","end":"2026-04-14T05:41:33.197432Z","steps":["trace[390058130] 'process raft request' (duration: 105.115687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:33.197691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.561241ms","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-14T05:41:33.197738Z","caller":"traceutil/trace.go:171","msg":"trace[415834527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"101.660523ms","start":"2026-04-14T05:41:33.096064Z","end":"2026-04-14T05:41:33.197724Z","steps":["trace[415834527] 'agreement among raft nodes before linearized reading' (duration: 101.48089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:33.197728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.898778ms","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-14T05:41:33.197924Z","caller":"traceutil/trace.go:171","msg":"trace[1981130819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"102.155812ms","start":"2026-04-14T05:41:33.095749Z","end":"2026-04-14T05:41:33.197905Z","steps":["trace[1981130819] 'agreement among raft nodes before linearized reading' (duration: 101.762765ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:38.865467Z","caller":"traceutil/trace.go:171","msg":"trace[1749134549] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"297.914627ms","start":"2026-04-14T05:41:38.567516Z","end":"2026-04-14T05:41:38.865431Z","steps":["trace[1749134549] 'process raft request' (duration: 297.684442ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:39.69653Z","caller":"traceutil/trace.go:171","msg":"trace[1121746951] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"476.034402ms","start":"2026-04-14T05:41:39.220465Z","end":"2026-04-14T05:41:39.6965Z","steps":["trace[1121746951] 'process raft request' (duration: 475.787548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:39.69673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:39.220441Z","time spent":"476.180355ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:41:39.696828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.261046ms","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-14T05:41:39.696906Z","caller":"traceutil/trace.go:171","msg":"trace[1017281763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"441.390178ms","start":"2026-04-14T05:41:39.255496Z","end":"2026-04-14T05:41:39.696886Z","steps":["trace[1017281763] 'agreement among raft nodes before linearized reading' (duration: 441.135042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:39.696945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:39.255476Z","time spent":"441.46289ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:41:39.697362Z","caller":"traceutil/trace.go:171","msg":"trace[1784369130] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"440.911819ms","start":"2026-04-14T05:41:39.255541Z","end":"2026-04-14T05:41:39.696453Z","steps":["trace[1784369130] 'read index received' (duration: 440.642594ms)","trace[1784369130] 'applied index is now lower than readState.Index' (duration: 268.105µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:39.697574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.040246ms","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-14T05:41:39.697607Z","caller":"traceutil/trace.go:171","msg":"trace[208358251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"415.118318ms","start":"2026-04-14T05:41:39.282482Z","end":"2026-04-14T05:41:39.6976Z","steps":["trace[208358251] 'agreement among raft nodes before linearized reading' (duration: 415.025336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:39.697628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:39.282458Z","time spent":"415.165259ms","remote":"127.0.0.1:45504","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-14T05:41:39.697665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.300978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:41:39.697749Z","caller":"traceutil/trace.go:171","msg":"trace[867690537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"410.465972ms","start":"2026-04-14T05:41:39.287264Z","end":"2026-04-14T05:41:39.69773Z","steps":["trace[867690537] 'agreement among raft nodes before linearized reading' (duration: 410.255398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:39.697735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.802601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T05:41:39.697803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:39.287243Z","time spent":"410.549483ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T05:41:39.697858Z","caller":"traceutil/trace.go:171","msg":"trace[786004213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1166; }","duration":"408.964094ms","start":"2026-04-14T05:41:39.288855Z","end":"2026-04-14T05:41:39.697819Z","steps":["trace[786004213] 'agreement among raft nodes before linearized reading' (duration: 408.657298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:39.697937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:39.288844Z","time spent":"409.049205ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T05:41:56.542939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.121026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T05:41:56.575395Z","caller":"traceutil/trace.go:171","msg":"trace[199175135] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.189339614s","start":"2026-04-14T05:41:55.386034Z","end":"2026-04-14T05:41:56.575373Z","steps":["trace[199175135] 'process raft request' (duration: 1.189146779s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.575538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.385998Z","time spent":"1.189468276s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:41:56.575623Z","caller":"traceutil/trace.go:171","msg":"trace[148087101] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1273; }","duration":"786.983722ms","start":"2026-04-14T05:41:55.788613Z","end":"2026-04-14T05:41:56.575597Z","steps":["trace[148087101] 'read index received' (duration: 366.149836ms)","trace[148087101] 'applied index is now lower than readState.Index' (duration: 420.832596ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:41:56.57604Z","caller":"traceutil/trace.go:171","msg":"trace[742112778] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"811.019469ms","start":"2026-04-14T05:41:55.765003Z","end":"2026-04-14T05:41:56.576023Z","steps":["trace[742112778] 'process raft request' (duration: 810.323537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.576053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.449441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T05:41:56.576103Z","caller":"traceutil/trace.go:171","msg":"trace[1509162744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"787.539401ms","start":"2026-04-14T05:41:55.788551Z","end":"2026-04-14T05:41:56.57609Z","steps":["trace[1509162744] 'agreement among raft nodes before linearized reading' (duration: 787.350268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.576135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.788535Z","time spent":"787.593583ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T05:41:56.576191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.764978Z","time spent":"811.10629ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:41:56.576521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.787098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:41:56.576568Z","caller":"traceutil/trace.go:171","msg":"trace[1029141428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"764.85586ms","start":"2026-04-14T05:41:55.811699Z","end":"2026-04-14T05:41:56.576555Z","steps":["trace[1029141428] 'agreement among raft nodes before linearized reading' (duration: 764.717717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.576606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.811688Z","time spent":"764.908791ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:41:56.576732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.71985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:41:56.576785Z","caller":"traceutil/trace.go:171","msg":"trace[1139263031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"133.811902ms","start":"2026-04-14T05:41:56.442958Z","end":"2026-04-14T05:41:56.57677Z","steps":["trace[1139263031] 'agreement among raft nodes before linearized reading' (duration: 133.723699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.576868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.55316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:41:56.576908Z","caller":"traceutil/trace.go:171","msg":"trace[1897213690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"753.610201ms","start":"2026-04-14T05:41:55.823287Z","end":"2026-04-14T05:41:56.576897Z","steps":["trace[1897213690] 'agreement among raft nodes before linearized reading' (duration: 753.499319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.576942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.823279Z","time spent":"753.655283ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T05:41:56.577166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.806152ms","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-14T05:41:56.577207Z","caller":"traceutil/trace.go:171","msg":"trace[229141576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"755.862622ms","start":"2026-04-14T05:41:55.821332Z","end":"2026-04-14T05:41:56.577194Z","steps":["trace[229141576] 'agreement among raft nodes before linearized reading' (duration: 755.7471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.577245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:41:55.821323Z","time spent":"755.908963ms","remote":"127.0.0.1:45504","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-14T05:41:56.81104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.576089ms","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-14T05:41:56.811168Z","caller":"traceutil/trace.go:171","msg":"trace[1503353312] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1228; }","duration":"124.732152ms","start":"2026-04-14T05:41:56.686417Z","end":"2026-04-14T05:41:56.811149Z","steps":["trace[1503353312] 'range keys from in-memory index tree' (duration: 124.562849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:56.811802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.633684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:41:56.812024Z","caller":"traceutil/trace.go:171","msg":"trace[1409328006] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"231.614792ms","start":"2026-04-14T05:41:56.580391Z","end":"2026-04-14T05:41:56.812005Z","steps":["trace[1409328006] 'process raft request' (duration: 87.518329ms)","trace[1409328006] 'compare' (duration: 142.966632ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:41:56.812545Z","caller":"traceutil/trace.go:171","msg":"trace[1762656578] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"231.835607ms","start":"2026-04-14T05:41:56.580697Z","end":"2026-04-14T05:41:56.812532Z","steps":["trace[1762656578] 'process raft request' (duration: 231.185524ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:56.813074Z","caller":"traceutil/trace.go:171","msg":"trace[160017612] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"231.752824ms","start":"2026-04-14T05:41:56.581308Z","end":"2026-04-14T05:41:56.813061Z","steps":["trace[160017612] 'process raft request' (duration: 231.179534ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:56.813602Z","caller":"traceutil/trace.go:171","msg":"trace[732551041] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"231.987029ms","start":"2026-04-14T05:41:56.581534Z","end":"2026-04-14T05:41:56.813521Z","steps":["trace[732551041] 'process raft request' (duration: 231.462159ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:57.045716Z","caller":"traceutil/trace.go:171","msg":"trace[330581523] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"195.821075ms","start":"2026-04-14T05:41:56.849869Z","end":"2026-04-14T05:41:57.04569Z","steps":["trace[330581523] 'process raft request' (duration: 96.921254ms)","trace[330581523] 'compare' (duration: 98.594846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:41:58.938306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.251149ms","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-14T05:41:58.93838Z","caller":"traceutil/trace.go:171","msg":"trace[4662490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"120.358381ms","start":"2026-04-14T05:41:58.818003Z","end":"2026-04-14T05:41:58.938361Z","steps":["trace[4662490] 'range keys from in-memory index tree' (duration: 120.116406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:58.938451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.812488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T05:41:58.938554Z","caller":"traceutil/trace.go:171","msg":"trace[1908729139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"121.972071ms","start":"2026-04-14T05:41:58.816546Z","end":"2026-04-14T05:41:58.938519Z","steps":["trace[1908729139] 'range keys from in-memory index tree' (duration: 121.580073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:58.938654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.761159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:41:58.938768Z","caller":"traceutil/trace.go:171","msg":"trace[2118226050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"119.884023ms","start":"2026-04-14T05:41:58.818863Z","end":"2026-04-14T05:41:58.938747Z","steps":["trace[2118226050] 'range keys from in-memory index tree' (duration: 119.599256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:41:58.93868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.229426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:41:58.938819Z","caller":"traceutil/trace.go:171","msg":"trace[813616478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"121.428861ms","start":"2026-04-14T05:41:58.817381Z","end":"2026-04-14T05:41:58.93881Z","steps":["trace[813616478] 'range keys from in-memory index tree' (duration: 121.094664ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:59.178635Z","caller":"traceutil/trace.go:171","msg":"trace[1829766953] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"236.34323ms","start":"2026-04-14T05:41:58.942271Z","end":"2026-04-14T05:41:59.178614Z","steps":["trace[1829766953] 'process raft request' (duration: 236.200647ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:59.22912Z","caller":"traceutil/trace.go:171","msg":"trace[283856700] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"285.600668ms","start":"2026-04-14T05:41:58.943492Z","end":"2026-04-14T05:41:59.229092Z","steps":["trace[283856700] 'process raft request' (duration: 285.323793ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:59.229436Z","caller":"traceutil/trace.go:171","msg":"trace[238976435] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"285.799181ms","start":"2026-04-14T05:41:58.943602Z","end":"2026-04-14T05:41:59.229402Z","steps":["trace[238976435] 'process raft request' (duration: 285.440195ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:59.229616Z","caller":"traceutil/trace.go:171","msg":"trace[401375949] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"285.956084ms","start":"2026-04-14T05:41:58.943649Z","end":"2026-04-14T05:41:59.229605Z","steps":["trace[401375949] 'process raft request' (duration: 285.660268ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:41:59.443241Z","caller":"traceutil/trace.go:171","msg":"trace[1557708827] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"208.874829ms","start":"2026-04-14T05:41:59.234317Z","end":"2026-04-14T05:41:59.443192Z","steps":["trace[1557708827] 'process raft request' (duration: 134.689648ms)","trace[1557708827] 'compare' (duration: 74.019428ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:42:10.915669Z","caller":"traceutil/trace.go:171","msg":"trace[700122375] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"208.086315ms","start":"2026-04-14T05:42:10.707553Z","end":"2026-04-14T05:42:10.915639Z","steps":["trace[700122375] 'process raft request' (duration: 207.947392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.888545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.877583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-14T05:42:11.888607Z","caller":"traceutil/trace.go:171","msg":"trace[325436054] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1332; }","duration":"798.982676ms","start":"2026-04-14T05:42:11.089609Z","end":"2026-04-14T05:42:11.888591Z","steps":["trace[325436054] 'range keys from in-memory index tree' (duration: 798.70285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.88864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.089588Z","time spent":"799.045257ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-14T05:42:11.889027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.36975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-14T05:42:11.889049Z","caller":"traceutil/trace.go:171","msg":"trace[1172791293] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1332; }","duration":"714.435601ms","start":"2026-04-14T05:42:11.174606Z","end":"2026-04-14T05:42:11.889042Z","steps":["trace[1172791293] 'range keys from in-memory index tree' (duration: 714.213147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.889067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.174584Z","time spent":"714.478191ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-14T05:42:11.889079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.440432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T05:42:11.889133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.737735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T05:42:11.889179Z","caller":"traceutil/trace.go:171","msg":"trace[522947502] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1332; }","duration":"750.561204ms","start":"2026-04-14T05:42:11.138594Z","end":"2026-04-14T05:42:11.889155Z","steps":["trace[522947502] 'count revisions from in-memory index tree' (duration: 750.29678ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:11.889189Z","caller":"traceutil/trace.go:171","msg":"trace[745071018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"397.816416ms","start":"2026-04-14T05:42:11.491359Z","end":"2026-04-14T05:42:11.889175Z","steps":["trace[745071018] 'range keys from in-memory index tree' (duration: 397.623363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.889227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.491346Z","time spent":"397.872587ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T05:42:11.889258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.516482ms","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-14T05:42:11.889279Z","caller":"traceutil/trace.go:171","msg":"trace[1109990170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"606.575583ms","start":"2026-04-14T05:42:11.282697Z","end":"2026-04-14T05:42:11.889273Z","steps":["trace[1109990170] 'range keys from in-memory index tree' (duration: 606.41664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.889274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.138532Z","time spent":"750.683546ms","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:42:11.889296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.282677Z","time spent":"606.614914ms","remote":"127.0.0.1:45504","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-14T05:42:11.889362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.359314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-14T05:42:11.889381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.062638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T05:42:11.889404Z","caller":"traceutil/trace.go:171","msg":"trace[1341721756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"603.125859ms","start":"2026-04-14T05:42:11.286272Z","end":"2026-04-14T05:42:11.889398Z","steps":["trace[1341721756] 'range keys from in-memory index tree' (duration: 602.893046ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:11.889412Z","caller":"traceutil/trace.go:171","msg":"trace[1269871846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"668.439294ms","start":"2026-04-14T05:42:11.220957Z","end":"2026-04-14T05:42:11.889396Z","steps":["trace[1269871846] 'range keys from in-memory index tree' (duration: 668.18389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.889426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.28625Z","time spent":"603.16533ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T05:42:11.889445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.22094Z","time spent":"668.497916ms","remote":"127.0.0.1:45504","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-14T05:42:11.889674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.641873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:42:11.88976Z","caller":"traceutil/trace.go:171","msg":"trace[1299396704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"449.641512ms","start":"2026-04-14T05:42:11.440099Z","end":"2026-04-14T05:42:11.88974Z","steps":["trace[1299396704] 'range keys from in-memory index tree' (duration: 448.522571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.889858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.440083Z","time spent":"449.713533ms","remote":"127.0.0.1:45230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:42:11.891453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.62823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T05:42:11.891515Z","caller":"traceutil/trace.go:171","msg":"trace[2069011426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"603.594287ms","start":"2026-04-14T05:42:11.287909Z","end":"2026-04-14T05:42:11.891503Z","steps":["trace[2069011426] 'range keys from in-memory index tree' (duration: 601.423396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:11.891543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:42:11.287899Z","time spent":"603.635119ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:42:23.874569Z","caller":"traceutil/trace.go:171","msg":"trace[343661441] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"106.01838ms","start":"2026-04-14T05:42:23.768524Z","end":"2026-04-14T05:42:23.874542Z","steps":["trace[343661441] 'process raft request' (duration: 105.839436ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:26.852246Z","caller":"traceutil/trace.go:171","msg":"trace[1872081068] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"122.108963ms","start":"2026-04-14T05:42:26.730115Z","end":"2026-04-14T05:42:26.852224Z","steps":["trace[1872081068] 'process raft request' (duration: 121.891059ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:27.357432Z","caller":"traceutil/trace.go:171","msg":"trace[71956656] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"108.919424ms","start":"2026-04-14T05:42:27.248483Z","end":"2026-04-14T05:42:27.357402Z","steps":["trace[71956656] 'process raft request' (duration: 108.664149ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:28.406925Z","caller":"traceutil/trace.go:171","msg":"trace[1994236729] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"225.622284ms","start":"2026-04-14T05:42:28.181276Z","end":"2026-04-14T05:42:28.406899Z","steps":["trace[1994236729] 'process raft request' (duration: 225.463191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:28.414847Z","caller":"traceutil/trace.go:171","msg":"trace[1352489129] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1466; }","duration":"134.73574ms","start":"2026-04-14T05:42:28.280086Z","end":"2026-04-14T05:42:28.414821Z","steps":["trace[1352489129] 'read index received' (duration: 126.653777ms)","trace[1352489129] 'applied index is now lower than readState.Index' (duration: 8.080553ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:42:28.415012Z","caller":"traceutil/trace.go:171","msg":"trace[563781086] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"232.910672ms","start":"2026-04-14T05:42:28.182063Z","end":"2026-04-14T05:42:28.414974Z","steps":["trace[563781086] 'process raft request' (duration: 232.571336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:42:28.415332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.22874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T05:42:28.415693Z","caller":"traceutil/trace.go:171","msg":"trace[730077880] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1414; }","duration":"135.670478ms","start":"2026-04-14T05:42:28.280009Z","end":"2026-04-14T05:42:28.41568Z","steps":["trace[730077880] 'agreement among raft nodes before linearized reading' (duration: 134.955905ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:46.335749Z","caller":"traceutil/trace.go:171","msg":"trace[344135558] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"104.514741ms","start":"2026-04-14T05:42:46.231195Z","end":"2026-04-14T05:42:46.33571Z","steps":["trace[344135558] 'process raft request' (duration: 104.215005ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:42:59.30718Z","caller":"traceutil/trace.go:171","msg":"trace[164897938] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"101.530035ms","start":"2026-04-14T05:42:59.205621Z","end":"2026-04-14T05:42:59.307151Z","steps":["trace[164897938] 'process raft request' (duration: 101.381292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:35.279261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.055365ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T05:43:35.41709Z","caller":"traceutil/trace.go:171","msg":"trace[520128965] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"132.693898ms","start":"2026-04-14T05:43:35.284358Z","end":"2026-04-14T05:43:35.417052Z","steps":["trace[520128965] 'process raft request' (duration: 132.427733ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:44:11.064918Z","caller":"traceutil/trace.go:171","msg":"trace[1865050910] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"129.161281ms","start":"2026-04-14T05:44:10.935721Z","end":"2026-04-14T05:44:11.064883Z","steps":["trace[1865050910] 'process raft request' (duration: 128.900466ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:45:32.155761Z","caller":"traceutil/trace.go:171","msg":"trace[1029121515] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"231.734596ms","start":"2026-04-14T05:45:31.923977Z","end":"2026-04-14T05:45:32.155711Z","steps":["trace[1029121515] 'process raft request' (duration: 231.072993ms)"],"step_count":1}