{"level":"warn","ts":"2026-03-19T08:59:56.761268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T08:59:56.761351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.78:2380","--initial-cluster=instance=https://199.19.213.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.78: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-03-19T08:59:56.76142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T08:59:56.761439Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-03-19T08:59:56.76146Z","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-03-19T08:59:56.762135Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"]} {"level":"info","ts":"2026-03-19T08:59:56.762223Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.78: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-03-19T08:59:56.766663Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.197751ms"} {"level":"info","ts":"2026-03-19T08:59:56.773677Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"173f1df9994fe3b5","cluster-id":"f38512da1b70c9fc"} {"level":"info","ts":"2026-03-19T08:59:56.773755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T08:59:56.773778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 0"} {"level":"info","ts":"2026-03-19T08:59:56.773796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 173f1df9994fe3b5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T08:59:56.773816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 1"} {"level":"info","ts":"2026-03-19T08:59:56.773862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"warn","ts":"2026-03-19T08:59:56.785891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T08:59:56.788675Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T08:59:56.790561Z","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-03-19T08:59:56.792775Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"173f1df9994fe3b5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T08:59:56.792942Z","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-03-19T08:59:56.793017Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"173f1df9994fe3b5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T08:59:56.79308Z","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-03-19T08:59:56.79324Z","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-03-19T08:59:56.797796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"info","ts":"2026-03-19T08:59:56.798004Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","added-peer-id":"173f1df9994fe3b5","added-peer-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-03-19T08:59:56.799956Z","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-03-19T08:59:56.800031Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-03-19T08:59:56.800226Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-03-19T08:59:56.802173Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"173f1df9994fe3b5","initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T08:59:56.802249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T08:59:57.574268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T08:59:57.574332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T08:59:57.57437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgPreVoteResp from 173f1df9994fe3b5 at term 1"} {"level":"info","ts":"2026-03-19T08:59:57.57451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became candidate at term 2"} {"level":"info","ts":"2026-03-19T08:59:57.574531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgVoteResp from 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-03-19T08:59:57.574544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became leader at term 2"} {"level":"info","ts":"2026-03-19T08:59:57.574611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 173f1df9994fe3b5 elected leader 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-03-19T08:59:57.577474Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"173f1df9994fe3b5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.78:2379]}","request-path":"/0/members/173f1df9994fe3b5/attributes","cluster-id":"f38512da1b70c9fc","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T08:59:57.577507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T08:59:57.57771Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:59:57.578062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T08:59:57.578103Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T08:59:57.578131Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T08:59:57.578574Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:59:57.578719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:59:57.578763Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:59:57.580125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.78:2379"} {"level":"info","ts":"2026-03-19T08:59:57.580851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-19T09:00:18.532874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.634812ms","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-03-19T09:00:18.533495Z","caller":"traceutil/trace.go:171","msg":"trace[202234321] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:451; }","duration":"207.272465ms","start":"2026-03-19T09:00:18.326171Z","end":"2026-03-19T09:00:18.533443Z","steps":["trace[202234321] 'range keys from in-memory index tree' (duration: 206.603871ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:18.757776Z","caller":"traceutil/trace.go:171","msg":"trace[199734966] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"196.601129ms","start":"2026-03-19T09:00:18.561153Z","end":"2026-03-19T09:00:18.757754Z","steps":["trace[199734966] 'process raft request' (duration: 196.477345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:19.990141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.210085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:19.990584Z","caller":"traceutil/trace.go:171","msg":"trace[500361703] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"760.814958ms","start":"2026-03-19T09:00:19.229741Z","end":"2026-03-19T09:00:19.990556Z","steps":["trace[500361703] 'process raft request' (duration: 289.381867ms)","trace[500361703] 'compare' (duration: 470.012361ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:19.990661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:19.229727Z","time spent":"760.89751ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:21.481304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204641424s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:21.481425Z","caller":"traceutil/trace.go:171","msg":"trace[165058162] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"1.674859058s","start":"2026-03-19T09:00:19.806547Z","end":"2026-03-19T09:00:21.481406Z","steps":["trace[165058162] 'read index received' (duration: 470.02393ms)","trace[165058162] 'applied index is now lower than readState.Index' (duration: 1.204830208s)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:21.481498Z","caller":"traceutil/trace.go:171","msg":"trace[1492238999] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"2.017670845s","start":"2026-03-19T09:00:19.463773Z","end":"2026-03-19T09:00:21.481444Z","steps":["trace[1492238999] 'process raft request' (duration: 812.800176ms)","trace[1492238999] 'compare' (duration: 1.204468381s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:21.481711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:19.46375Z","time spent":"2.017845759s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:21.481549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674993971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:00:21.481808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.950147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-19T09:00:21.481839Z","caller":"traceutil/trace.go:171","msg":"trace[2043088114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"1.675295207s","start":"2026-03-19T09:00:19.806508Z","end":"2026-03-19T09:00:21.481803Z","steps":["trace[2043088114] 'agreement among raft nodes before linearized reading' (duration: 1.674997631s)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:21.481886Z","caller":"traceutil/trace.go:171","msg":"trace[26459498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"256.11393ms","start":"2026-03-19T09:00:21.225752Z","end":"2026-03-19T09:00:21.481866Z","steps":["trace[26459498] 'agreement among raft nodes before linearized reading' (duration: 255.944036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:21.481911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:19.806488Z","time spent":"1.67540639s","remote":"127.0.0.1:41840","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-03-19T09:00:21.48188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.984105ms","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-03-19T09:00:21.482013Z","caller":"traceutil/trace.go:171","msg":"trace[1099353101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"720.155959ms","start":"2026-03-19T09:00:20.761833Z","end":"2026-03-19T09:00:21.481989Z","steps":["trace[1099353101] 'agreement among raft nodes before linearized reading' (duration: 719.949275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:21.482062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:20.761819Z","time spent":"720.23374ms","remote":"127.0.0.1:42112","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-03-19T09:00:21.482117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.294765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T09:00:21.48215Z","caller":"traceutil/trace.go:171","msg":"trace[1605086945] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:455; }","duration":"966.351457ms","start":"2026-03-19T09:00:20.515789Z","end":"2026-03-19T09:00:21.48214Z","steps":["trace[1605086945] 'agreement among raft nodes before linearized reading' (duration: 966.292346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:21.48218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:20.515774Z","time spent":"966.398428ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.78\" "} {"level":"warn","ts":"2026-03-19T09:00:21.482345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526292441s","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-03-19T09:00:21.483897Z","caller":"traceutil/trace.go:171","msg":"trace[416003413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"1.526508874s","start":"2026-03-19T09:00:19.955953Z","end":"2026-03-19T09:00:21.482462Z","steps":["trace[416003413] 'agreement among raft nodes before linearized reading' (duration: 1.525633187s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:21.483997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:19.955934Z","time spent":"1.528031867s","remote":"127.0.0.1:42112","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-03-19T09:00:21.996448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408193463581534427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:00:23.172631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.17378ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-19T09:00:23.172869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.483474Z","time spent":"1.689389314s","remote":"127.0.0.1:41880","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T09:00:23.172953Z","caller":"traceutil/trace.go:171","msg":"trace[1178698848] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:469; }","duration":"1.676663656s","start":"2026-03-19T09:00:21.496272Z","end":"2026-03-19T09:00:23.172935Z","steps":["trace[1178698848] 'read index received' (duration: 874.151418ms)","trace[1178698848] 'applied index is now lower than readState.Index' (duration: 802.511438ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:23.173051Z","caller":"traceutil/trace.go:171","msg":"trace[756739008] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.678297629s","start":"2026-03-19T09:00:21.494726Z","end":"2026-03-19T09:00:23.173024Z","steps":["trace[756739008] 'process raft request' (duration: 1.678114595s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.173188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.494718Z","time spent":"1.67838546s","remote":"127.0.0.1:42112","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-03-19T09:00:23.173202Z","caller":"traceutil/trace.go:171","msg":"trace[240943682] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.678875141s","start":"2026-03-19T09:00:21.494313Z","end":"2026-03-19T09:00:23.173189Z","steps":["trace[240943682] 'process raft request' (duration: 1.678411112s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.173271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.49429Z","time spent":"1.678939743s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:23.173335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677004202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:23.17345Z","caller":"traceutil/trace.go:171","msg":"trace[627484579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"1.677193527s","start":"2026-03-19T09:00:21.496237Z","end":"2026-03-19T09:00:23.17343Z","steps":["trace[627484579] 'agreement among raft nodes before linearized reading' (duration: 1.676932161s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.173529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.496225Z","time spent":"1.677293408s","remote":"127.0.0.1:41840","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-03-19T09:00:23.173152Z","caller":"traceutil/trace.go:171","msg":"trace[1417999013] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"1.678128805s","start":"2026-03-19T09:00:21.495013Z","end":"2026-03-19T09:00:23.173142Z","steps":["trace[1417999013] 'process raft request' (duration: 1.67788983s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.173913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.494987Z","time spent":"1.678766758s","remote":"127.0.0.1:42112","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-03-19T09:00:23.856066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.501641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:23.856219Z","caller":"traceutil/trace.go:171","msg":"trace[1116036507] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"682.543643ms","start":"2026-03-19T09:00:23.173662Z","end":"2026-03-19T09:00:23.856205Z","steps":["trace[1116036507] 'process raft request' (duration: 682.499892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.856281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:23.17364Z","time spent":"682.609804ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:00:23.856422Z","caller":"traceutil/trace.go:171","msg":"trace[1497289042] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"683.43055ms","start":"2026-03-19T09:00:23.172984Z","end":"2026-03-19T09:00:23.856414Z","steps":["trace[1497289042] 'read index received' (duration: 393.623413ms)","trace[1497289042] 'applied index is now lower than readState.Index' (duration: 289.806187ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:23.856402Z","caller":"traceutil/trace.go:171","msg":"trace[1272368959] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.182535614s","start":"2026-03-19T09:00:22.673831Z","end":"2026-03-19T09:00:23.856366Z","steps":["trace[1272368959] 'process raft request' (duration: 892.672286ms)","trace[1272368959] 'compare' (duration: 289.265265ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:23.856548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859175996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-19T09:00:23.856566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:22.673795Z","time spent":"1.182673507s","remote":"127.0.0.1:42112","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-03-19T09:00:23.856579Z","caller":"traceutil/trace.go:171","msg":"trace[989874228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:460; }","duration":"1.859208596s","start":"2026-03-19T09:00:21.997363Z","end":"2026-03-19T09:00:23.856572Z","steps":["trace[989874228] 'agreement among raft nodes before linearized reading' (duration: 1.859137965s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.856602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:21.997315Z","time spent":"1.859282138s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T09:00:23.856716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.479861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:23.856738Z","caller":"traceutil/trace.go:171","msg":"trace[1036412078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"681.538562ms","start":"2026-03-19T09:00:23.175194Z","end":"2026-03-19T09:00:23.856733Z","steps":["trace[1036412078] 'agreement among raft nodes before linearized reading' (duration: 681.507332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:23.856753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:23.175173Z","time spent":"681.576713ms","remote":"127.0.0.1:41826","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-03-19T09:00:24.361769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408193463581534439,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T09:00:24.466432Z","caller":"traceutil/trace.go:171","msg":"trace[748679615] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"605.962081ms","start":"2026-03-19T09:00:23.860424Z","end":"2026-03-19T09:00:24.466387Z","steps":["trace[748679615] 'process raft request' (duration: 600.051611ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:24.466657Z","caller":"traceutil/trace.go:171","msg":"trace[1816092900] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"604.830029ms","start":"2026-03-19T09:00:23.861724Z","end":"2026-03-19T09:00:24.466554Z","steps":["trace[1816092900] 'read index received' (duration: 598.680934ms)","trace[1816092900] 'applied index is now lower than readState.Index' (duration: 6.146335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:24.466732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.991612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-19T09:00:24.466773Z","caller":"traceutil/trace.go:171","msg":"trace[1220557802] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:461; }","duration":"605.064683ms","start":"2026-03-19T09:00:23.861689Z","end":"2026-03-19T09:00:24.466753Z","steps":["trace[1220557802] 'agreement among raft nodes before linearized reading' (duration: 604.981701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:24.466794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:23.861674Z","time spent":"605.115374ms","remote":"127.0.0.1:42124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-19T09:00:24.467252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.92309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:24.469356Z","caller":"traceutil/trace.go:171","msg":"trace[784261317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"605.036692ms","start":"2026-03-19T09:00:23.86428Z","end":"2026-03-19T09:00:24.469316Z","steps":["trace[784261317] 'agreement among raft nodes before linearized reading' (duration: 602.546381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:24.46945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:23.864265Z","time spent":"605.154195ms","remote":"127.0.0.1:41840","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-03-19T09:00:24.469861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:23.860413Z","time spent":"609.375902ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:00:37.137811Z","caller":"traceutil/trace.go:171","msg":"trace[1585215984] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"330.835953ms","start":"2026-03-19T09:00:36.806942Z","end":"2026-03-19T09:00:37.137778Z","steps":["trace[1585215984] 'read index received' (duration: 330.540557ms)","trace[1585215984] 'applied index is now lower than readState.Index' (duration: 294.116µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:37.138242Z","caller":"traceutil/trace.go:171","msg":"trace[888991090] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"553.075214ms","start":"2026-03-19T09:00:36.585121Z","end":"2026-03-19T09:00:37.138196Z","steps":["trace[888991090] 'process raft request' (duration: 552.451422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:37.13846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:36.585105Z","time spent":"553.250547ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:37.139852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.882094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:37.139929Z","caller":"traceutil/trace.go:171","msg":"trace[1215514305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"333.036137ms","start":"2026-03-19T09:00:36.806871Z","end":"2026-03-19T09:00:37.139907Z","steps":["trace[1215514305] 'agreement among raft nodes before linearized reading' (duration: 331.043537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:37.139974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:36.806839Z","time spent":"333.125679ms","remote":"127.0.0.1:41840","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-03-19T09:00:37.358377Z","caller":"traceutil/trace.go:171","msg":"trace[1901522767] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"109.91319ms","start":"2026-03-19T09:00:37.248428Z","end":"2026-03-19T09:00:37.358341Z","steps":["trace[1901522767] 'process raft request' (duration: 109.846419ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:37.358434Z","caller":"traceutil/trace.go:171","msg":"trace[1409185505] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"110.458461ms","start":"2026-03-19T09:00:37.247938Z","end":"2026-03-19T09:00:37.358396Z","steps":["trace[1409185505] 'process raft request' (duration: 97.440106ms)","trace[1409185505] 'compare' (duration: 12.75087ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:38.165463Z","caller":"traceutil/trace.go:171","msg":"trace[1590941141] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"711.968871ms","start":"2026-03-19T09:00:37.45347Z","end":"2026-03-19T09:00:38.165439Z","steps":["trace[1590941141] 'process raft request' (duration: 711.811188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:38.165623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:37.453424Z","time spent":"712.118065ms","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:00:38.199528Z","caller":"traceutil/trace.go:171","msg":"trace[1904251720] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"387.488028ms","start":"2026-03-19T09:00:37.812004Z","end":"2026-03-19T09:00:38.199492Z","steps":["trace[1904251720] 'read index received' (duration: 353.429083ms)","trace[1904251720] 'applied index is now lower than readState.Index' (duration: 34.056705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:38.199724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.660562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:38.199847Z","caller":"traceutil/trace.go:171","msg":"trace[1066086332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"387.882987ms","start":"2026-03-19T09:00:37.811945Z","end":"2026-03-19T09:00:38.199828Z","steps":["trace[1066086332] 'agreement among raft nodes before linearized reading' (duration: 387.659962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:38.199952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:37.811905Z","time spent":"388.037679ms","remote":"127.0.0.1:41840","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-03-19T09:00:39.776855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408193463581534599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:00:40.27793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408193463581534599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:00:40.412349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.264796021s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T09:00:40.413049Z","caller":"traceutil/trace.go:171","msg":"trace[1462366448] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"1.136716461s","start":"2026-03-19T09:00:39.276294Z","end":"2026-03-19T09:00:40.413011Z","steps":["trace[1462366448] 'read index received' (duration: 1.136332363s)","trace[1462366448] 'applied index is now lower than readState.Index' (duration: 382.558µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:40.413389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137054847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-19T09:00:40.413469Z","caller":"traceutil/trace.go:171","msg":"trace[645117037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.13717057s","start":"2026-03-19T09:00:39.27628Z","end":"2026-03-19T09:00:40.413451Z","steps":["trace[645117037] 'agreement among raft nodes before linearized reading' (duration: 1.136894104s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:40.413469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.907571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:00:40.413529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:39.276226Z","time spent":"1.137288112s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T09:00:40.413603Z","caller":"traceutil/trace.go:171","msg":"trace[1886286750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"607.125675ms","start":"2026-03-19T09:00:39.806446Z","end":"2026-03-19T09:00:40.413571Z","steps":["trace[1886286750] 'agreement among raft nodes before linearized reading' (duration: 606.86494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:40.413684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:39.80642Z","time spent":"607.254878ms","remote":"127.0.0.1:41840","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-03-19T09:00:40.413553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050577734s","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-03-19T09:00:40.413234Z","caller":"traceutil/trace.go:171","msg":"trace[2073301040] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.265765441s","start":"2026-03-19T09:00:39.147401Z","end":"2026-03-19T09:00:40.413166Z","steps":["trace[2073301040] 'process raft request' (duration: 1.265350283s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:40.413633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050705187s","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-03-19T09:00:40.414051Z","caller":"traceutil/trace.go:171","msg":"trace[511128262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"1.051155276s","start":"2026-03-19T09:00:39.362865Z","end":"2026-03-19T09:00:40.41402Z","steps":["trace[511128262] 'agreement among raft nodes before linearized reading' (duration: 1.050473962s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:40.414064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:39.147367Z","time spent":"1.266568917s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:40.414132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:39.362861Z","time spent":"1.051251328s","remote":"127.0.0.1:42112","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":"info","ts":"2026-03-19T09:00:40.413954Z","caller":"traceutil/trace.go:171","msg":"trace[2031664905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.051038704s","start":"2026-03-19T09:00:39.362828Z","end":"2026-03-19T09:00:40.413867Z","steps":["trace[2031664905] 'agreement among raft nodes before linearized reading' (duration: 1.050427152s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:40.41429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:39.36282Z","time spent":"1.051450932s","remote":"127.0.0.1:42112","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-03-19T09:00:45.778402Z","caller":"traceutil/trace.go:171","msg":"trace[1218756617] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"120.632619ms","start":"2026-03-19T09:00:45.657737Z","end":"2026-03-19T09:00:45.77837Z","steps":["trace[1218756617] 'process raft request' (duration: 119.144109ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:58.764861Z","caller":"traceutil/trace.go:171","msg":"trace[1108965050] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"232.930168ms","start":"2026-03-19T09:00:58.531898Z","end":"2026-03-19T09:00:58.764828Z","steps":["trace[1108965050] 'read index received' (duration: 232.673962ms)","trace[1108965050] 'applied index is now lower than readState.Index' (duration: 254.376µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:58.764914Z","caller":"traceutil/trace.go:171","msg":"trace[344964367] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"234.088512ms","start":"2026-03-19T09:00:58.530788Z","end":"2026-03-19T09:00:58.764876Z","steps":["trace[344964367] 'process raft request' (duration: 233.853537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:58.765217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.624484ms","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-03-19T09:00:58.765258Z","caller":"traceutil/trace.go:171","msg":"trace[97624966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"211.731406ms","start":"2026-03-19T09:00:58.553516Z","end":"2026-03-19T09:00:58.765247Z","steps":["trace[97624966] 'agreement among raft nodes before linearized reading' (duration: 211.486691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:58.765294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.255926ms","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-03-19T09:00:58.765376Z","caller":"traceutil/trace.go:171","msg":"trace[1437522194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"211.396388ms","start":"2026-03-19T09:00:58.553963Z","end":"2026-03-19T09:00:58.765359Z","steps":["trace[1437522194] 'agreement among raft nodes before linearized reading' (duration: 211.168444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:58.765686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.765624ms","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-03-19T09:00:58.765748Z","caller":"traceutil/trace.go:171","msg":"trace[1408253291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"233.871997ms","start":"2026-03-19T09:00:58.531852Z","end":"2026-03-19T09:00:58.765724Z","steps":["trace[1408253291] 'agreement among raft nodes before linearized reading' (duration: 233.129302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:59.420789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.282527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:59.420972Z","caller":"traceutil/trace.go:171","msg":"trace[1621396537] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:696; }","duration":"616.639659ms","start":"2026-03-19T09:00:58.804316Z","end":"2026-03-19T09:00:59.420955Z","steps":["trace[1621396537] 'read index received' (duration: 39.670169ms)","trace[1621396537] 'applied index is now lower than readState.Index' (duration: 576.96818ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:59.421052Z","caller":"traceutil/trace.go:171","msg":"trace[1883318823] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"649.866666ms","start":"2026-03-19T09:00:58.771178Z","end":"2026-03-19T09:00:59.421044Z","steps":["trace[1883318823] 'process raft request' (duration: 649.750044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:59.421093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:58.771158Z","time spent":"649.912398ms","remote":"127.0.0.1:42112","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-03-19T09:00:59.421122Z","caller":"traceutil/trace.go:171","msg":"trace[520043863] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"651.116501ms","start":"2026-03-19T09:00:58.769966Z","end":"2026-03-19T09:00:59.421083Z","steps":["trace[520043863] 'process raft request' (duration: 650.920007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:59.421294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.992156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:00:59.421296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:58.769951Z","time spent":"651.260464ms","remote":"127.0.0.1:42112","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-03-19T09:00:59.421321Z","caller":"traceutil/trace.go:171","msg":"trace[406988305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"617.063147ms","start":"2026-03-19T09:00:58.804248Z","end":"2026-03-19T09:00:59.421311Z","steps":["trace[406988305] 'agreement among raft nodes before linearized reading' (duration: 617.002896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:59.421344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:58.804222Z","time spent":"617.118188ms","remote":"127.0.0.1:41840","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-03-19T09:00:59.4213Z","caller":"traceutil/trace.go:171","msg":"trace[898296076] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"651.318795ms","start":"2026-03-19T09:00:58.769931Z","end":"2026-03-19T09:00:59.42125Z","steps":["trace[898296076] 'process raft request' (duration: 74.037959ms)","trace[898296076] 'compare' (duration: 576.168194ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:59.421459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:58.769902Z","time spent":"651.477169ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:00.371126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.890825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:00.371647Z","caller":"traceutil/trace.go:171","msg":"trace[114079070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"567.024788ms","start":"2026-03-19T09:00:59.804178Z","end":"2026-03-19T09:01:00.371203Z","steps":["trace[114079070] 'range keys from in-memory index tree' (duration: 566.820833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:00.371711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:59.804159Z","time spent":"567.539028ms","remote":"127.0.0.1:41840","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-03-19T09:01:00.372528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.318336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T09:01:00.372584Z","caller":"traceutil/trace.go:171","msg":"trace[1272259490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"155.442199ms","start":"2026-03-19T09:01:00.217125Z","end":"2026-03-19T09:01:00.372567Z","steps":["trace[1272259490] 'range keys from in-memory index tree' (duration: 155.120012ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:09.716862Z","caller":"traceutil/trace.go:171","msg":"trace[889718889] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"225.87137ms","start":"2026-03-19T09:01:09.490963Z","end":"2026-03-19T09:01:09.716835Z","steps":["trace[889718889] 'process raft request' (duration: 225.673526ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:10.224752Z","caller":"traceutil/trace.go:171","msg":"trace[1851396470] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"733.13686ms","start":"2026-03-19T09:01:09.491566Z","end":"2026-03-19T09:01:10.224703Z","steps":["trace[1851396470] 'process raft request' (duration: 732.898425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.225987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.491561Z","time spent":"733.273603ms","remote":"127.0.0.1:42112","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-03-19T09:01:10.226177Z","caller":"traceutil/trace.go:171","msg":"trace[1931903460] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"493.391243ms","start":"2026-03-19T09:01:09.731514Z","end":"2026-03-19T09:01:10.224905Z","steps":["trace[1931903460] 'read index received' (duration: 492.874002ms)","trace[1931903460] 'applied index is now lower than readState.Index' (duration: 514.541µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:10.226747Z","caller":"traceutil/trace.go:171","msg":"trace[1531531540] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"735.108731ms","start":"2026-03-19T09:01:09.491612Z","end":"2026-03-19T09:01:10.22672Z","steps":["trace[1531531540] 'process raft request' (duration: 733.041549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.226862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.333762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:01:10.22702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.491607Z","time spent":"735.288224ms","remote":"127.0.0.1:42112","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-03-19T09:01:10.22829Z","caller":"traceutil/trace.go:171","msg":"trace[1674006010] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:748; }","duration":"496.799633ms","start":"2026-03-19T09:01:09.731455Z","end":"2026-03-19T09:01:10.228255Z","steps":["trace[1674006010] 'agreement among raft nodes before linearized reading' (duration: 495.348073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.228359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.731429Z","time spent":"496.910635ms","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-03-19T09:01:12.352558Z","caller":"traceutil/trace.go:171","msg":"trace[441996647] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"113.617933ms","start":"2026-03-19T09:01:12.238887Z","end":"2026-03-19T09:01:12.352505Z","steps":["trace[441996647] 'process raft request' (duration: 74.604775ms)","trace[441996647] 'compare' (duration: 38.848355ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:24.530319Z","caller":"traceutil/trace.go:171","msg":"trace[261428050] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"122.396645ms","start":"2026-03-19T09:01:24.407892Z","end":"2026-03-19T09:01:24.530288Z","steps":["trace[261428050] 'read index received' (duration: 122.280443ms)","trace[261428050] 'applied index is now lower than readState.Index' (duration: 114.502µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:24.530464Z","caller":"traceutil/trace.go:171","msg":"trace[1708938942] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"205.035338ms","start":"2026-03-19T09:01:24.325387Z","end":"2026-03-19T09:01:24.530422Z","steps":["trace[1708938942] 'process raft request' (duration: 204.723622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:24.530723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.759603ms","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-03-19T09:01:24.530826Z","caller":"traceutil/trace.go:171","msg":"trace[1295783498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"122.931906ms","start":"2026-03-19T09:01:24.407875Z","end":"2026-03-19T09:01:24.530807Z","steps":["trace[1295783498] 'agreement among raft nodes before linearized reading' (duration: 122.535728ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:25.058269Z","caller":"traceutil/trace.go:171","msg":"trace[986904337] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"518.435851ms","start":"2026-03-19T09:01:24.539785Z","end":"2026-03-19T09:01:25.058221Z","steps":["trace[986904337] 'read index received' (duration: 498.191821ms)","trace[986904337] 'applied index is now lower than readState.Index' (duration: 20.24214ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:25.058432Z","caller":"traceutil/trace.go:171","msg":"trace[1816382069] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"521.583437ms","start":"2026-03-19T09:01:24.536834Z","end":"2026-03-19T09:01:25.058417Z","steps":["trace[1816382069] 'process raft request' (duration: 501.141853ms)","trace[1816382069] 'compare' (duration: 20.000205ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:25.058581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:24.53675Z","time spent":"521.719169ms","remote":"127.0.0.1:42112","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-03-19T09:01:25.058775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.807287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:25.058928Z","caller":"traceutil/trace.go:171","msg":"trace[891905274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"251.010451ms","start":"2026-03-19T09:01:24.807864Z","end":"2026-03-19T09:01:25.058874Z","steps":["trace[891905274] 'agreement among raft nodes before linearized reading' (duration: 250.810577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:25.058966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.158347ms","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-03-19T09:01:25.059061Z","caller":"traceutil/trace.go:171","msg":"trace[1749359853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"519.297019ms","start":"2026-03-19T09:01:24.539743Z","end":"2026-03-19T09:01:25.05904Z","steps":["trace[1749359853] 'agreement among raft nodes before linearized reading' (duration: 519.020273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:25.059105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:24.539727Z","time spent":"519.370301ms","remote":"127.0.0.1:42112","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-03-19T09:01:25.788951Z","caller":"traceutil/trace.go:171","msg":"trace[1356352232] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"724.92792ms","start":"2026-03-19T09:01:25.063954Z","end":"2026-03-19T09:01:25.788882Z","steps":["trace[1356352232] 'process raft request' (duration: 724.733405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:25.789228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.063929Z","time spent":"725.199106ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:25.823736Z","caller":"traceutil/trace.go:171","msg":"trace[1759512358] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"429.985399ms","start":"2026-03-19T09:01:25.393717Z","end":"2026-03-19T09:01:25.823703Z","steps":["trace[1759512358] 'process raft request' (duration: 429.926508ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:25.823825Z","caller":"traceutil/trace.go:171","msg":"trace[748481084] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"757.929404ms","start":"2026-03-19T09:01:25.065848Z","end":"2026-03-19T09:01:25.823777Z","steps":["trace[748481084] 'process raft request' (duration: 757.656878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:25.823927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.393682Z","time spent":"430.146253ms","remote":"127.0.0.1:42112","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-03-19T09:01:25.82407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.065835Z","time spent":"758.097398ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:38.283381Z","caller":"traceutil/trace.go:171","msg":"trace[442552375] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"371.699872ms","start":"2026-03-19T09:01:37.911655Z","end":"2026-03-19T09:01:38.283355Z","steps":["trace[442552375] 'process raft request' (duration: 371.573349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:38.283506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:37.911636Z","time spent":"371.811634ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:38.763702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.850502ms","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-03-19T09:01:38.763817Z","caller":"traceutil/trace.go:171","msg":"trace[657454052] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:879; }","duration":"437.974394ms","start":"2026-03-19T09:01:38.325825Z","end":"2026-03-19T09:01:38.763799Z","steps":["trace[657454052] 'range keys from in-memory index tree' (duration: 437.834171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:38.763809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.719647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:38.763957Z","caller":"traceutil/trace.go:171","msg":"trace[146174355] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"851.682196ms","start":"2026-03-19T09:01:37.912265Z","end":"2026-03-19T09:01:38.763947Z","steps":["trace[146174355] 'process raft request' (duration: 430.732344ms)","trace[146174355] 'compare' (duration: 420.562824ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:38.76397Z","caller":"traceutil/trace.go:171","msg":"trace[1516311565] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:911; }","duration":"824.404441ms","start":"2026-03-19T09:01:37.939535Z","end":"2026-03-19T09:01:38.76394Z","steps":["trace[1516311565] 'read index received' (duration: 343.732722ms)","trace[1516311565] 'applied index is now lower than readState.Index' (duration: 480.669899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:38.76403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:37.91224Z","time spent":"851.739938ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:38.764154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.626195ms","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-03-19T09:01:38.764192Z","caller":"traceutil/trace.go:171","msg":"trace[2060212946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"824.705327ms","start":"2026-03-19T09:01:37.939479Z","end":"2026-03-19T09:01:38.764185Z","steps":["trace[2060212946] 'agreement among raft nodes before linearized reading' (duration: 824.565714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:38.764209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:37.939465Z","time spent":"824.741057ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:01:38.764152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.600745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:01:38.764305Z","caller":"traceutil/trace.go:171","msg":"trace[844655207] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:880; }","duration":"448.792659ms","start":"2026-03-19T09:01:38.315473Z","end":"2026-03-19T09:01:38.764266Z","steps":["trace[844655207] 'agreement among raft nodes before linearized reading' (duration: 448.595225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:38.764346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.538586ms","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-03-19T09:01:38.76439Z","caller":"traceutil/trace.go:171","msg":"trace[1486613961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"175.622418ms","start":"2026-03-19T09:01:38.588757Z","end":"2026-03-19T09:01:38.764379Z","steps":["trace[1486613961] 'agreement among raft nodes before linearized reading' (duration: 175.432744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:38.765191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:38.315447Z","time spent":"449.702129ms","remote":"127.0.0.1:42270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:01:43.290957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408193463581535401,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T09:01:43.341746Z","caller":"traceutil/trace.go:171","msg":"trace[1863678943] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"551.325963ms","start":"2026-03-19T09:01:42.790395Z","end":"2026-03-19T09:01:43.341721Z","steps":["trace[1863678943] 'read index received' (duration: 551.061047ms)","trace[1863678943] 'applied index is now lower than readState.Index' (duration: 263.336µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:43.341855Z","caller":"traceutil/trace.go:171","msg":"trace[1316192581] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"916.955429ms","start":"2026-03-19T09:01:42.42488Z","end":"2026-03-19T09:01:43.341835Z","steps":["trace[1316192581] 'process raft request' (duration: 916.700144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:43.341948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:42.424863Z","time spent":"917.02427ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:43.342174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.374029ms","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-03-19T09:01:43.342184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.877734ms","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-03-19T09:01:43.3422Z","caller":"traceutil/trace.go:171","msg":"trace[301804646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"112.453871ms","start":"2026-03-19T09:01:43.229739Z","end":"2026-03-19T09:01:43.342192Z","steps":["trace[301804646] 'agreement among raft nodes before linearized reading' (duration: 112.354668ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:43.342227Z","caller":"traceutil/trace.go:171","msg":"trace[1500648069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"507.951356ms","start":"2026-03-19T09:01:42.834264Z","end":"2026-03-19T09:01:43.342215Z","steps":["trace[1500648069] 'agreement among raft nodes before linearized reading' (duration: 507.858384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:43.342268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:42.834217Z","time spent":"508.043058ms","remote":"127.0.0.1:42112","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-03-19T09:01:43.342328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.503715ms","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-03-19T09:01:43.342431Z","caller":"traceutil/trace.go:171","msg":"trace[376922915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"532.692128ms","start":"2026-03-19T09:01:42.809718Z","end":"2026-03-19T09:01:43.34241Z","steps":["trace[376922915] 'agreement among raft nodes before linearized reading' (duration: 532.339579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:43.342479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:42.809695Z","time spent":"532.77675ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:01:43.342682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.68691ms","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-03-19T09:01:43.342725Z","caller":"traceutil/trace.go:171","msg":"trace[1122289239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"552.371215ms","start":"2026-03-19T09:01:42.790343Z","end":"2026-03-19T09:01:43.342714Z","steps":["trace[1122289239] 'agreement among raft nodes before linearized reading' (duration: 551.503826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:43.342757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:42.790329Z","time spent":"552.418466ms","remote":"127.0.0.1:42112","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-03-19T09:01:55.175191Z","caller":"traceutil/trace.go:171","msg":"trace[572103353] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"483.876306ms","start":"2026-03-19T09:01:54.691298Z","end":"2026-03-19T09:01:55.175174Z","steps":["trace[572103353] 'read index received' (duration: 483.790913ms)","trace[572103353] 'applied index is now lower than readState.Index' (duration: 84.543µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:55.175282Z","caller":"traceutil/trace.go:171","msg":"trace[296946176] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"692.498389ms","start":"2026-03-19T09:01:54.482778Z","end":"2026-03-19T09:01:55.175276Z","steps":["trace[296946176] 'process raft request' (duration: 692.289553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.17535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:54.482763Z","time spent":"692.534359ms","remote":"127.0.0.1:42254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:55.175472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.383065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:55.175559Z","caller":"traceutil/trace.go:171","msg":"trace[296113431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"370.487747ms","start":"2026-03-19T09:01:54.80505Z","end":"2026-03-19T09:01:55.175537Z","steps":["trace[296113431] 'agreement among raft nodes before linearized reading' (duration: 370.363775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.175575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.287965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:55.175599Z","caller":"traceutil/trace.go:171","msg":"trace[410281901] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:943; }","duration":"484.339925ms","start":"2026-03-19T09:01:54.691252Z","end":"2026-03-19T09:01:55.175592Z","steps":["trace[410281901] 'agreement among raft nodes before linearized reading' (duration: 484.296734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.175601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:54.805042Z","time spent":"370.549388ms","remote":"127.0.0.1:41840","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-03-19T09:01:55.175624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:54.691235Z","time spent":"484.385816ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:01:55.626347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.451534ms","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-03-19T09:01:55.626421Z","caller":"traceutil/trace.go:171","msg":"trace[1941972827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"172.554406ms","start":"2026-03-19T09:01:55.453847Z","end":"2026-03-19T09:01:55.626401Z","steps":["trace[1941972827] 'range keys from in-memory index tree' (duration: 172.333591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.626705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.922693ms","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-03-19T09:01:55.626779Z","caller":"traceutil/trace.go:171","msg":"trace[1676503389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"174.023106ms","start":"2026-03-19T09:01:55.452737Z","end":"2026-03-19T09:01:55.62676Z","steps":["trace[1676503389] 'range keys from in-memory index tree' (duration: 173.76653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.626784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.124109ms","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-03-19T09:01:55.627073Z","caller":"traceutil/trace.go:171","msg":"trace[1248797385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"177.412755ms","start":"2026-03-19T09:01:55.449653Z","end":"2026-03-19T09:01:55.627065Z","steps":["trace[1248797385] 'range keys from in-memory index tree' (duration: 177.025998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:55.627202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.61816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T09:01:55.627245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.081588ms","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-03-19T09:01:55.627283Z","caller":"traceutil/trace.go:171","msg":"trace[1395671749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"189.167929ms","start":"2026-03-19T09:01:55.438104Z","end":"2026-03-19T09:01:55.627272Z","steps":["trace[1395671749] 'range keys from in-memory index tree' (duration: 188.871334ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:55.62728Z","caller":"traceutil/trace.go:171","msg":"trace[711827979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"177.723772ms","start":"2026-03-19T09:01:55.449538Z","end":"2026-03-19T09:01:55.627262Z","steps":["trace[711827979] 'range keys from in-memory index tree' (duration: 177.416945ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:56.104061Z","caller":"traceutil/trace.go:171","msg":"trace[1946597867] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"473.433939ms","start":"2026-03-19T09:01:55.630601Z","end":"2026-03-19T09:01:56.104035Z","steps":["trace[1946597867] 'process raft request' (duration: 473.236565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:56.104326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:55.630575Z","time spent":"473.589702ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:56.105172Z","caller":"traceutil/trace.go:171","msg":"trace[1842360557] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"464.418001ms","start":"2026-03-19T09:01:55.640725Z","end":"2026-03-19T09:01:56.105143Z","steps":["trace[1842360557] 'process raft request' (duration: 464.081114ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:56.105192Z","caller":"traceutil/trace.go:171","msg":"trace[1813280726] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"464.418533ms","start":"2026-03-19T09:01:55.640758Z","end":"2026-03-19T09:01:56.105176Z","steps":["trace[1813280726] 'process raft request' (duration: 464.082225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:56.105266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:55.640754Z","time spent":"464.471704ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:56.105273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:55.640719Z","time spent":"464.500294ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:56.105389Z","caller":"traceutil/trace.go:171","msg":"trace[889536090] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"464.581395ms","start":"2026-03-19T09:01:55.640777Z","end":"2026-03-19T09:01:56.105359Z","steps":["trace[889536090] 'process raft request' (duration: 464.092404ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:56.105421Z","caller":"traceutil/trace.go:171","msg":"trace[2015060174] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"464.767299ms","start":"2026-03-19T09:01:55.640643Z","end":"2026-03-19T09:01:56.105411Z","steps":["trace[2015060174] 'process raft request' (duration: 464.103345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:56.105517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:55.64063Z","time spent":"464.858331ms","remote":"127.0.0.1:42112","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-03-19T09:01:56.105603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:55.640775Z","time spent":"464.696338ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:56.391322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.403875ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T09:01:58.217038Z","caller":"traceutil/trace.go:171","msg":"trace[320290898] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"105.770271ms","start":"2026-03-19T09:01:58.11124Z","end":"2026-03-19T09:01:58.21701Z","steps":["trace[320290898] 'process raft request' (duration: 105.609418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:58.678307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.711946ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:01:58.678411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.3293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:58.678572Z","caller":"traceutil/trace.go:171","msg":"trace[1733409334] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"564.866732ms","start":"2026-03-19T09:01:58.113693Z","end":"2026-03-19T09:01:58.67856Z","steps":["trace[1733409334] 'process raft request' (duration: 564.842751ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:58.678568Z","caller":"traceutil/trace.go:171","msg":"trace[1593874240] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"566.580476ms","start":"2026-03-19T09:01:58.111962Z","end":"2026-03-19T09:01:58.678543Z","steps":["trace[1593874240] 'process raft request' (duration: 350.067803ms)","trace[1593874240] 'compare' (duration: 216.179457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:58.678606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.113687Z","time spent":"564.904602ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:58.678607Z","caller":"traceutil/trace.go:171","msg":"trace[211494913] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"566.351081ms","start":"2026-03-19T09:01:58.112242Z","end":"2026-03-19T09:01:58.678594Z","steps":["trace[211494913] 'process raft request' (duration: 566.238779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:58.678656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.112167Z","time spent":"566.464484ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:58.67865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.111956Z","time spent":"566.646398ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:58.678731Z","caller":"traceutil/trace.go:171","msg":"trace[1417949955] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"566.140107ms","start":"2026-03-19T09:01:58.112584Z","end":"2026-03-19T09:01:58.678724Z","steps":["trace[1417949955] 'process raft request' (duration: 565.932943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:58.678768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.112576Z","time spent":"566.174419ms","remote":"127.0.0.1:42112","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-03-19T09:01:58.678403Z","caller":"traceutil/trace.go:171","msg":"trace[1025399383] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:951; }","duration":"352.818718ms","start":"2026-03-19T09:01:58.32557Z","end":"2026-03-19T09:01:58.678389Z","steps":["trace[1025399383] 'range keys from in-memory index tree' (duration: 352.697056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:59.220955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.321082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:59.221089Z","caller":"traceutil/trace.go:171","msg":"trace[557892282] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"502.37315ms","start":"2026-03-19T09:01:58.718696Z","end":"2026-03-19T09:01:59.221069Z","steps":["trace[557892282] 'read index received' (duration: 374.809964ms)","trace[557892282] 'applied index is now lower than readState.Index' (duration: 127.561246ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:59.221186Z","caller":"traceutil/trace.go:171","msg":"trace[1551560450] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"719.11547ms","start":"2026-03-19T09:01:58.502059Z","end":"2026-03-19T09:01:59.221175Z","steps":["trace[1551560450] 'process raft request' (duration: 591.455491ms)","trace[1551560450] 'compare' (duration: 126.76029ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:59.221238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.502022Z","time spent":"719.193241ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T09:01:59.221502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.797379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:59.221533Z","caller":"traceutil/trace.go:171","msg":"trace[22141415] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:956; }","duration":"502.866701ms","start":"2026-03-19T09:01:58.718657Z","end":"2026-03-19T09:01:59.221523Z","steps":["trace[22141415] 'agreement among raft nodes before linearized reading' (duration: 502.762579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:59.221558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.718638Z","time spent":"502.914971ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-19T09:01:59.221698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.368054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:59.221801Z","caller":"traceutil/trace.go:171","msg":"trace[1147845613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"414.529218ms","start":"2026-03-19T09:01:58.807251Z","end":"2026-03-19T09:01:59.22178Z","steps":["trace[1147845613] 'agreement among raft nodes before linearized reading' (duration: 414.350134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:59.221889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:58.80723Z","time spent":"414.6514ms","remote":"127.0.0.1:41840","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-03-19T09:01:59.454753Z","caller":"traceutil/trace.go:171","msg":"trace[1961070338] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"227.606926ms","start":"2026-03-19T09:01:59.227129Z","end":"2026-03-19T09:01:59.454736Z","steps":["trace[1961070338] 'process raft request' (duration: 159.510165ms)","trace[1961070338] 'compare' (duration: 67.862076ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:59.667323Z","caller":"traceutil/trace.go:171","msg":"trace[327370420] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"201.458364ms","start":"2026-03-19T09:01:59.465839Z","end":"2026-03-19T09:01:59.667298Z","steps":["trace[327370420] 'process raft request' (duration: 201.414753ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:59.667763Z","caller":"traceutil/trace.go:171","msg":"trace[374965005] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"346.397286ms","start":"2026-03-19T09:01:59.321347Z","end":"2026-03-19T09:01:59.667744Z","steps":["trace[374965005] 'process raft request' (duration: 248.416816ms)","trace[374965005] 'compare' (duration: 97.331797ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:59.667873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:59.321312Z","time spent":"346.493419ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:01:59.66828Z","caller":"traceutil/trace.go:171","msg":"trace[1233523154] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"339.502214ms","start":"2026-03-19T09:01:59.328766Z","end":"2026-03-19T09:01:59.668269Z","steps":["trace[1233523154] 'read index received' (duration: 57.962461ms)","trace[1233523154] 'applied index is now lower than readState.Index' (duration: 281.537603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:59.668432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.656547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:01:59.668473Z","caller":"traceutil/trace.go:171","msg":"trace[1829640552] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:960; }","duration":"339.761559ms","start":"2026-03-19T09:01:59.328702Z","end":"2026-03-19T09:01:59.668464Z","steps":["trace[1829640552] 'agreement among raft nodes before linearized reading' (duration: 339.656747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:59.668504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:59.328687Z","time spent":"339.809842ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:01:59.668557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.724886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:01:59.668603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.200962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-19T09:01:59.668625Z","caller":"traceutil/trace.go:171","msg":"trace[1002518978] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:960; }","duration":"265.808448ms","start":"2026-03-19T09:01:59.402798Z","end":"2026-03-19T09:01:59.668606Z","steps":["trace[1002518978] 'agreement among raft nodes before linearized reading' (duration: 265.678874ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:59.668636Z","caller":"traceutil/trace.go:171","msg":"trace[1599909684] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:960; }","duration":"204.248972ms","start":"2026-03-19T09:01:59.464377Z","end":"2026-03-19T09:01:59.668626Z","steps":["trace[1599909684] 'agreement among raft nodes before linearized reading' (duration: 204.113269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:00.305173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.588049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:00.305375Z","caller":"traceutil/trace.go:171","msg":"trace[199207177] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"568.294715ms","start":"2026-03-19T09:01:59.737058Z","end":"2026-03-19T09:02:00.305352Z","steps":["trace[199207177] 'read index received' (duration: 239.234826ms)","trace[199207177] 'applied index is now lower than readState.Index' (duration: 329.057599ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:00.305979Z","caller":"traceutil/trace.go:171","msg":"trace[172730057] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"630.682368ms","start":"2026-03-19T09:01:59.67521Z","end":"2026-03-19T09:02:00.305893Z","steps":["trace[172730057] 'process raft request' (duration: 301.206651ms)","trace[172730057] 'compare' (duration: 328.16614ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:00.30616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:59.675192Z","time spent":"630.880551ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:00.306573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.50931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:00.306672Z","caller":"traceutil/trace.go:171","msg":"trace[565579357] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:961; }","duration":"569.646012ms","start":"2026-03-19T09:01:59.737011Z","end":"2026-03-19T09:02:00.306657Z","steps":["trace[565579357] 'agreement among raft nodes before linearized reading' (duration: 569.508649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:00.306929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:59.736985Z","time spent":"569.772015ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-03-19T09:02:00.587248Z","caller":"traceutil/trace.go:171","msg":"trace[1608763002] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"261.12947ms","start":"2026-03-19T09:02:00.326087Z","end":"2026-03-19T09:02:00.587216Z","steps":["trace[1608763002] 'read index received' (duration: 183.217366ms)","trace[1608763002] 'applied index is now lower than readState.Index' (duration: 77.910734ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:00.587426Z","caller":"traceutil/trace.go:171","msg":"trace[690979908] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"272.496077ms","start":"2026-03-19T09:02:00.314905Z","end":"2026-03-19T09:02:00.587401Z","steps":["trace[690979908] 'process raft request' (duration: 194.445019ms)","trace[690979908] 'compare' (duration: 77.73077ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:00.587554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.436267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:02:00.587592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.523577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:00.587665Z","caller":"traceutil/trace.go:171","msg":"trace[150047902] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:962; }","duration":"146.65488ms","start":"2026-03-19T09:02:00.440992Z","end":"2026-03-19T09:02:00.587647Z","steps":["trace[150047902] 'agreement among raft nodes before linearized reading' (duration: 146.496047ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:00.587624Z","caller":"traceutil/trace.go:171","msg":"trace[16157668] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:962; }","duration":"261.55212ms","start":"2026-03-19T09:02:00.326052Z","end":"2026-03-19T09:02:00.587604Z","steps":["trace[16157668] 'agreement among raft nodes before linearized reading' (duration: 261.429958ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:00.995776Z","caller":"traceutil/trace.go:171","msg":"trace[1577613248] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"308.685906ms","start":"2026-03-19T09:02:00.687064Z","end":"2026-03-19T09:02:00.99575Z","steps":["trace[1577613248] 'process raft request' (duration: 305.539851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:00.995939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:00.687053Z","time spent":"308.813268ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:01.045199Z","caller":"traceutil/trace.go:171","msg":"trace[302794927] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"358.09145ms","start":"2026-03-19T09:02:00.687076Z","end":"2026-03-19T09:02:01.045167Z","steps":["trace[302794927] 'process raft request' (duration: 357.864295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:01.045432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:00.687067Z","time spent":"358.240473ms","remote":"127.0.0.1:42112","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-03-19T09:02:01.045512Z","caller":"traceutil/trace.go:171","msg":"trace[47819978] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"358.369455ms","start":"2026-03-19T09:02:00.687118Z","end":"2026-03-19T09:02:01.045488Z","steps":["trace[47819978] 'process raft request' (duration: 358.003248ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:01.045624Z","caller":"traceutil/trace.go:171","msg":"trace[2067739675] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1001; }","duration":"291.206374ms","start":"2026-03-19T09:02:00.754405Z","end":"2026-03-19T09:02:01.045612Z","steps":["trace[2067739675] 'read index received' (duration: 238.41961ms)","trace[2067739675] 'applied index is now lower than readState.Index' (duration: 52.781764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:01.045724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.871509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:02:01.045722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.313056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:01.04577Z","caller":"traceutil/trace.go:171","msg":"trace[400766100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"238.935521ms","start":"2026-03-19T09:02:00.806816Z","end":"2026-03-19T09:02:01.045752Z","steps":["trace[400766100] 'agreement among raft nodes before linearized reading' (duration: 238.87393ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:01.04577Z","caller":"traceutil/trace.go:171","msg":"trace[1550383532] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:967; }","duration":"291.403628ms","start":"2026-03-19T09:02:00.754354Z","end":"2026-03-19T09:02:01.045758Z","steps":["trace[1550383532] 'agreement among raft nodes before linearized reading' (duration: 291.320916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:01.045802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.61494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:02:01.046189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:00.687099Z","time spent":"358.472677ms","remote":"127.0.0.1:42112","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-03-19T09:02:01.046334Z","caller":"traceutil/trace.go:171","msg":"trace[86764547] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:967; }","duration":"205.208711ms","start":"2026-03-19T09:02:00.841109Z","end":"2026-03-19T09:02:01.046317Z","steps":["trace[86764547] 'agreement among raft nodes before linearized reading' (duration: 204.594869ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:01.04648Z","caller":"traceutil/trace.go:171","msg":"trace[1394815597] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"357.323783ms","start":"2026-03-19T09:02:00.688331Z","end":"2026-03-19T09:02:01.045655Z","steps":["trace[1394815597] 'process raft request' (duration: 357.056607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:01.046581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:00.688318Z","time spent":"358.20898ms","remote":"127.0.0.1:42112","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-03-19T09:02:08.778244Z","caller":"traceutil/trace.go:171","msg":"trace[1543473200] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"150.149934ms","start":"2026-03-19T09:02:08.628057Z","end":"2026-03-19T09:02:08.778207Z","steps":["trace[1543473200] 'process raft request' (duration: 150.001111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.66027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:10.044249Z","caller":"traceutil/trace.go:171","msg":"trace[1688425101] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"1.237703641s","start":"2026-03-19T09:02:08.806529Z","end":"2026-03-19T09:02:10.044233Z","steps":["trace[1688425101] 'read index received' (duration: 260.790866ms)","trace[1688425101] 'applied index is now lower than readState.Index' (duration: 976.911425ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:10.044275Z","caller":"traceutil/trace.go:171","msg":"trace[473977496] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.262206818s","start":"2026-03-19T09:02:08.78204Z","end":"2026-03-19T09:02:10.044247Z","steps":["trace[473977496] 'process raft request' (duration: 285.343373ms)","trace[473977496] 'compare' (duration: 976.452026ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:10.044345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237823804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:10.044373Z","caller":"traceutil/trace.go:171","msg":"trace[1826113187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.237889285s","start":"2026-03-19T09:02:08.806475Z","end":"2026-03-19T09:02:10.044364Z","steps":["trace[1826113187] 'agreement among raft nodes before linearized reading' (duration: 1.237838414s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:08.806455Z","time spent":"1.237941106s","remote":"127.0.0.1:41840","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-03-19T09:02:10.044403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:08.782025Z","time spent":"1.262309481s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:10.044759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.566233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-19T09:02:10.044781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.00462ms","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-03-19T09:02:10.0448Z","caller":"traceutil/trace.go:171","msg":"trace[585769141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"944.624305ms","start":"2026-03-19T09:02:09.100167Z","end":"2026-03-19T09:02:10.044791Z","steps":["trace[585769141] 'agreement among raft nodes before linearized reading' (duration: 944.501622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.853661ms","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":"warn","ts":"2026-03-19T09:02:10.044831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.100157Z","time spent":"944.668276ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T09:02:10.044828Z","caller":"traceutil/trace.go:171","msg":"trace[1264614315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"943.080282ms","start":"2026-03-19T09:02:09.101733Z","end":"2026-03-19T09:02:10.044813Z","steps":["trace[1264614315] 'agreement among raft nodes before linearized reading' (duration: 942.934448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.226814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-19T09:02:10.044866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.101716Z","time spent":"943.143453ms","remote":"127.0.0.1:42112","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":"info","ts":"2026-03-19T09:02:10.044852Z","caller":"traceutil/trace.go:171","msg":"trace[73241965] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1023; }","duration":"867.947143ms","start":"2026-03-19T09:02:09.176887Z","end":"2026-03-19T09:02:10.044834Z","steps":["trace[73241965] 'agreement among raft nodes before linearized reading' (duration: 867.771578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.176871Z","time spent":"868.024574ms","remote":"127.0.0.1:42112","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-03-19T09:02:10.044879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.609781ms","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-03-19T09:02:10.044909Z","caller":"traceutil/trace.go:171","msg":"trace[1954188516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"951.367157ms","start":"2026-03-19T09:02:09.093518Z","end":"2026-03-19T09:02:10.044885Z","steps":["trace[1954188516] 'agreement among raft nodes before linearized reading' (duration: 951.07127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.044971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.093497Z","time spent":"951.463199ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T09:02:10.044977Z","caller":"traceutil/trace.go:171","msg":"trace[1968566084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"941.750123ms","start":"2026-03-19T09:02:09.103202Z","end":"2026-03-19T09:02:10.044952Z","steps":["trace[1968566084] 'agreement among raft nodes before linearized reading' (duration: 941.391505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.045037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.103187Z","time spent":"941.836666ms","remote":"127.0.0.1:42112","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-03-19T09:02:10.207008Z","caller":"traceutil/trace.go:171","msg":"trace[566997849] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"157.100482ms","start":"2026-03-19T09:02:10.049883Z","end":"2026-03-19T09:02:10.206984Z","steps":["trace[566997849] 'process raft request' (duration: 156.953539ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.220908Z","caller":"traceutil/trace.go:171","msg":"trace[2044381578] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"170.853483ms","start":"2026-03-19T09:02:10.050039Z","end":"2026-03-19T09:02:10.220893Z","steps":["trace[2044381578] 'process raft request' (duration: 170.72779ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.221069Z","caller":"traceutil/trace.go:171","msg":"trace[1740941848] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"170.944804ms","start":"2026-03-19T09:02:10.050113Z","end":"2026-03-19T09:02:10.221058Z","steps":["trace[1740941848] 'process raft request' (duration: 170.74131ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.221468Z","caller":"traceutil/trace.go:171","msg":"trace[1062430195] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"170.892073ms","start":"2026-03-19T09:02:10.050553Z","end":"2026-03-19T09:02:10.221445Z","steps":["trace[1062430195] 'process raft request' (duration: 170.469203ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.221715Z","caller":"traceutil/trace.go:171","msg":"trace[1192367506] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"170.877162ms","start":"2026-03-19T09:02:10.050824Z","end":"2026-03-19T09:02:10.221702Z","steps":["trace[1192367506] 'process raft request' (duration: 170.554286ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.222513Z","caller":"traceutil/trace.go:171","msg":"trace[1176520771] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1063; }","duration":"168.000212ms","start":"2026-03-19T09:02:10.054473Z","end":"2026-03-19T09:02:10.222462Z","steps":["trace[1176520771] 'read index received' (duration: 152.351541ms)","trace[1176520771] 'applied index is now lower than readState.Index' (duration: 15.635471ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:10.222643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.148115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:10.222681Z","caller":"traceutil/trace.go:171","msg":"trace[451605487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"168.216897ms","start":"2026-03-19T09:02:10.054453Z","end":"2026-03-19T09:02:10.22267Z","steps":["trace[451605487] 'agreement among raft nodes before linearized reading' (duration: 168.131635ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.222586Z","caller":"traceutil/trace.go:171","msg":"trace[1237541251] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"171.016756ms","start":"2026-03-19T09:02:10.051526Z","end":"2026-03-19T09:02:10.222543Z","steps":["trace[1237541251] 'process raft request' (duration: 170.023425ms)"],"step_count":1}