{"level":"warn","ts":"2026-03-18T11:45:14.179857Z","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-18T11:45:14.179942Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-18T11:45:14.180141Z","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-18T11:45:14.180157Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-03-18T11:45:14.180177Z","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-18T11:45:14.181052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-03-18T11:45:14.181182Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-18T11:45:14.186614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.168091ms"} {"level":"info","ts":"2026-03-18T11:45:14.197477Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-03-18T11:45:14.197556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T11:45:14.197575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-03-18T11:45:14.197618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T11:45:14.197629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-03-18T11:45:14.197667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-03-18T11:45:14.209635Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T11:45:14.21112Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T11:45:14.212483Z","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-18T11:45:14.214831Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T11:45:14.214965Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T11:45:14.215106Z","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-18T11:45:14.215183Z","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-18T11:45:14.215219Z","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-18T11:45:14.216881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-03-18T11:45:14.217638Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-03-18T11:45:14.218788Z","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-18T11:45:14.21898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T11:45:14.21894Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-03-18T11:45:14.219176Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-03-18T11:45:14.219026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T11:45:15.19787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T11:45:15.197924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T11:45:15.197959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-03-18T11:45:15.19798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-03-18T11:45:15.198121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-03-18T11:45:15.198154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-03-18T11:45:15.198165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-03-18T11:45:15.241317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T11:45:15.241345Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T11:45:15.241327Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:45:15.241486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T11:45:15.241961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T11:45:15.242035Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T11:45:15.24694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T11:45:15.24786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-03-18T11:45:15.258938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:45:15.259092Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:45:15.25914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-18T11:45:22.936317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.447628ms","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-18T11:45:22.936514Z","caller":"traceutil/trace.go:171","msg":"trace[1112993853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"196.730101ms","start":"2026-03-18T11:45:22.739758Z","end":"2026-03-18T11:45:22.936488Z","steps":["trace[1112993853] 'range keys from in-memory index tree' (duration: 196.285816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:22.936408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239409838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-18T11:45:22.936558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.140397ms","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-18T11:45:22.936738Z","caller":"traceutil/trace.go:171","msg":"trace[868080434] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"1.239845943s","start":"2026-03-18T11:45:21.696862Z","end":"2026-03-18T11:45:22.936708Z","steps":["trace[868080434] 'range keys from in-memory index tree' (duration: 1.239243366s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:22.936807Z","caller":"traceutil/trace.go:171","msg":"trace[1739710756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:326; }","duration":"197.499571ms","start":"2026-03-18T11:45:22.739277Z","end":"2026-03-18T11:45:22.936776Z","steps":["trace[1739710756] 'range keys from in-memory index tree' (duration: 196.878863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:22.936965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:21.696843Z","time spent":"1.240051996s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-18T11:45:24.694364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.253341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:45:24.694644Z","caller":"traceutil/trace.go:171","msg":"trace[854085707] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"131.169819ms","start":"2026-03-18T11:45:24.563417Z","end":"2026-03-18T11:45:24.694587Z","steps":["trace[854085707] 'compare' (duration: 120.10812ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:24.699531Z","caller":"traceutil/trace.go:171","msg":"trace[1862200856] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"102.530447ms","start":"2026-03-18T11:45:24.596989Z","end":"2026-03-18T11:45:24.69952Z","steps":["trace[1862200856] 'read index received' (duration: 102.438586ms)","trace[1862200856] 'applied index is now lower than readState.Index' (duration: 87.981µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:24.699689Z","caller":"traceutil/trace.go:171","msg":"trace[696403129] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"134.481331ms","start":"2026-03-18T11:45:24.56513Z","end":"2026-03-18T11:45:24.699612Z","steps":["trace[696403129] 'process raft request' (duration: 134.309469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:24.699734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.739659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-18T11:45:24.700029Z","caller":"traceutil/trace.go:171","msg":"trace[38615291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"103.045033ms","start":"2026-03-18T11:45:24.596973Z","end":"2026-03-18T11:45:24.700019Z","steps":["trace[38615291] 'agreement among raft nodes before linearized reading' (duration: 102.704479ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:27.097258Z","caller":"traceutil/trace.go:171","msg":"trace[1611666005] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"331.36877ms","start":"2026-03-18T11:45:26.765868Z","end":"2026-03-18T11:45:27.097237Z","steps":["trace[1611666005] 'process raft request' (duration: 331.178107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:27.097721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:26.765853Z","time spent":"331.4636ms","remote":"127.0.0.1:39692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:29.299735Z","caller":"traceutil/trace.go:171","msg":"trace[384081119] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"147.700449ms","start":"2026-03-18T11:45:29.152008Z","end":"2026-03-18T11:45:29.299709Z","steps":["trace[384081119] 'process raft request' (duration: 147.526467ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:29.323301Z","caller":"traceutil/trace.go:171","msg":"trace[1467259419] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"171.142297ms","start":"2026-03-18T11:45:29.152114Z","end":"2026-03-18T11:45:29.323257Z","steps":["trace[1467259419] 'process raft request' (duration: 170.959523ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:29.903422Z","caller":"traceutil/trace.go:171","msg":"trace[1826028283] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"251.073574ms","start":"2026-03-18T11:45:29.652314Z","end":"2026-03-18T11:45:29.903387Z","steps":["trace[1826028283] 'process raft request' (duration: 250.842151ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:31.479898Z","caller":"traceutil/trace.go:171","msg":"trace[696906762] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"152.707057ms","start":"2026-03-18T11:45:31.327158Z","end":"2026-03-18T11:45:31.479865Z","steps":["trace[696906762] 'read index received' (duration: 152.654256ms)","trace[696906762] 'applied index is now lower than readState.Index' (duration: 51.341µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:31.479926Z","caller":"traceutil/trace.go:171","msg":"trace[667830628] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"169.54248ms","start":"2026-03-18T11:45:31.310353Z","end":"2026-03-18T11:45:31.479895Z","steps":["trace[667830628] 'process raft request' (duration: 169.350128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:31.480088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.912529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T11:45:31.480137Z","caller":"traceutil/trace.go:171","msg":"trace[1029979370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"153.002319ms","start":"2026-03-18T11:45:31.327124Z","end":"2026-03-18T11:45:31.480126Z","steps":["trace[1029979370] 'agreement among raft nodes before linearized reading' (duration: 152.866298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:31.480311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.959896ms","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-18T11:45:31.480551Z","caller":"traceutil/trace.go:171","msg":"trace[2044051282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"113.255059ms","start":"2026-03-18T11:45:31.367269Z","end":"2026-03-18T11:45:31.480524Z","steps":["trace[2044051282] 'agreement among raft nodes before linearized reading' (duration: 112.876825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:31.715542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.575414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:45:31.715658Z","caller":"traceutil/trace.go:171","msg":"trace[1167890643] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"230.437596ms","start":"2026-03-18T11:45:31.485201Z","end":"2026-03-18T11:45:31.715638Z","steps":["trace[1167890643] 'process raft request' (duration: 39.67424ms)","trace[1167890643] 'compare' (duration: 190.340661ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:31.723583Z","caller":"traceutil/trace.go:171","msg":"trace[830539234] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"237.29151ms","start":"2026-03-18T11:45:31.486281Z","end":"2026-03-18T11:45:31.723572Z","steps":["trace[830539234] 'process raft request' (duration: 237.233389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:32.012441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.202898ms","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-18T11:45:32.012555Z","caller":"traceutil/trace.go:171","msg":"trace[2029310575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"104.368101ms","start":"2026-03-18T11:45:31.908146Z","end":"2026-03-18T11:45:32.012514Z","steps":["trace[2029310575] 'range keys from in-memory index tree' (duration: 104.009337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:34.220512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:45:34.720907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:45:35.221348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:45:35.722051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:45:36.215628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.724904058s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T11:45:36.21635Z","caller":"traceutil/trace.go:171","msg":"trace[1916574415] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"2.725701258s","start":"2026-03-18T11:45:33.490617Z","end":"2026-03-18T11:45:36.216319Z","steps":["trace[1916574415] 'process raft request' (duration: 2.725470176s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:36.216531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:33.490602Z","time spent":"2.72583865s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:45:36.411062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000432333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-18T11:45:36.411141Z","caller":"traceutil/trace.go:171","msg":"trace[2067823777] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000543744s","start":"2026-03-18T11:45:34.410579Z","end":"2026-03-18T11:45:36.411123Z","steps":["trace[2067823777] 'agreement among raft nodes before linearized reading' (duration: 2.000430203s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:36.411189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:34.410519Z","time spent":"2.000660215s","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 11:45:36 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T11:45:38.143822Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130374186s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-18T11:45:38.411968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000292735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-18T11:45:38.412064Z","caller":"traceutil/trace.go:171","msg":"trace[358978838] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000449427s","start":"2026-03-18T11:45:36.411589Z","end":"2026-03-18T11:45:38.412039Z","steps":["trace[358978838] 'agreement among raft nodes before linearized reading' (duration: 2.000331535s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:38.412116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:36.411565Z","time spent":"2.000541927s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 11:45:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T11:45:38.726669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999481349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T11:45:38.72708Z","caller":"traceutil/trace.go:171","msg":"trace[1543809066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999972773s","start":"2026-03-18T11:45:33.727049Z","end":"2026-03-18T11:45:38.727022Z","steps":["trace[1543809066] 'agreement among raft nodes before linearized reading' (duration: 4.999506328s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:38.727256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:33.72704Z","time spent":"5.000202246s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T11:45:39.074987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999010331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T11:45:39.075103Z","caller":"traceutil/trace.go:171","msg":"trace[774758598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999215683s","start":"2026-03-18T11:45:34.075856Z","end":"2026-03-18T11:45:39.075071Z","steps":["trace[774758598] 'agreement among raft nodes before linearized reading' (duration: 4.999060791s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:39.075216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:34.075832Z","time spent":"4.999371107s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T11:45:39.871263Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.454893094s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-18T11:45:40.280391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.750898008s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-18T11:45:40.280645Z","caller":"traceutil/trace.go:171","msg":"trace[1374061607] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"6.560160199s","start":"2026-03-18T11:45:33.720445Z","end":"2026-03-18T11:45:40.280605Z","steps":["trace[1374061607] 'read index received' (duration: 2.495467288s)","trace[1374061607] 'applied index is now lower than readState.Index' (duration: 4.064690871s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:40.28076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.56026288s","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-18T11:45:40.280843Z","caller":"traceutil/trace.go:171","msg":"trace[1266248039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"6.560433531s","start":"2026-03-18T11:45:33.720395Z","end":"2026-03-18T11:45:40.280829Z","steps":["trace[1266248039] 'agreement among raft nodes before linearized reading' (duration: 6.560266409s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.280917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:33.720368Z","time spent":"6.560506061s","remote":"127.0.0.1:39790","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-18T11:45:40.281309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.364580281s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:45:40.281404Z","caller":"traceutil/trace.go:171","msg":"trace[1937838863] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:419; }","duration":"6.364677422s","start":"2026-03-18T11:45:33.916713Z","end":"2026-03-18T11:45:40.281391Z","steps":["trace[1937838863] 'agreement among raft nodes before linearized reading' (duration: 6.364553961s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:40.281474Z","caller":"traceutil/trace.go:171","msg":"trace[364356817] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.865217768s","start":"2026-03-18T11:45:38.416243Z","end":"2026-03-18T11:45:40.281461Z","steps":["trace[364356817] 'process raft request' (duration: 1.864955475s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.281564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059790125s","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-18T11:45:40.281603Z","caller":"traceutil/trace.go:171","msg":"trace[1483337536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"2.059871706s","start":"2026-03-18T11:45:38.221719Z","end":"2026-03-18T11:45:40.281591Z","steps":["trace[1483337536] 'agreement among raft nodes before linearized reading' (duration: 2.059806485s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.28161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861383983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T11:45:40.281634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:38.221691Z","time spent":"2.059936357s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T11:45:40.281639Z","caller":"traceutil/trace.go:171","msg":"trace[963720396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"1.861479955s","start":"2026-03-18T11:45:38.42015Z","end":"2026-03-18T11:45:40.28163Z","steps":["trace[963720396] 'agreement among raft nodes before linearized reading' (duration: 1.861432364s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.281606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:38.416215Z","time spent":"1.865295289s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T11:45:40.281666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:38.420114Z","time spent":"1.861546065s","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T11:45:40.281956Z","caller":"traceutil/trace.go:171","msg":"trace[160726211] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"3.268568449s","start":"2026-03-18T11:45:37.013335Z","end":"2026-03-18T11:45:40.281903Z","steps":["trace[160726211] 'process raft request' (duration: 3.267214314s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.282169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:37.013306Z","time spent":"3.268749352s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:45:40.281655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926693061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-18T11:45:40.282469Z","caller":"traceutil/trace.go:171","msg":"trace[2073140296] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:419; }","duration":"1.927565361s","start":"2026-03-18T11:45:38.35488Z","end":"2026-03-18T11:45:40.282445Z","steps":["trace[2073140296] 'agreement among raft nodes before linearized reading' (duration: 1.92662684s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:40.283039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:38.354843Z","time spent":"1.927666243s","remote":"127.0.0.1:35090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-03-18T11:45:41.27698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.264494ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-18T11:45:41.277406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.284166Z","time spent":"993.23492ms","remote":"127.0.0.1:35090","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-18T11:45:41.27754Z","caller":"traceutil/trace.go:171","msg":"trace[314882465] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"992.983688ms","start":"2026-03-18T11:45:40.284519Z","end":"2026-03-18T11:45:41.277503Z","steps":["trace[314882465] 'read index received' (duration: 147.168407ms)","trace[314882465] 'applied index is now lower than readState.Index' (duration: 845.812561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:41.277778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.222251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-03-18T11:45:41.277818Z","caller":"traceutil/trace.go:171","msg":"trace[2139111944] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:419; }","duration":"993.613225ms","start":"2026-03-18T11:45:40.284196Z","end":"2026-03-18T11:45:41.277809Z","steps":["trace[2139111944] 'agreement among raft nodes before linearized reading' (duration: 993.450943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.277856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.284179Z","time spent":"993.668486ms","remote":"127.0.0.1:39692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-18T11:45:41.277933Z","caller":"traceutil/trace.go:171","msg":"trace[837381365] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"991.285428ms","start":"2026-03-18T11:45:40.286624Z","end":"2026-03-18T11:45:41.27791Z","steps":["trace[837381365] 'process raft request' (duration: 991.250258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.278102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.286609Z","time spent":"991.379539ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:41.278284Z","caller":"traceutil/trace.go:171","msg":"trace[1415486521] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"992.428951ms","start":"2026-03-18T11:45:40.285845Z","end":"2026-03-18T11:45:41.278274Z","steps":["trace[1415486521] 'process raft request' (duration: 991.913896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.278354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.285824Z","time spent":"992.492992ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:41.362776Z","caller":"traceutil/trace.go:171","msg":"trace[311904010] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"584.656099ms","start":"2026-03-18T11:45:40.778078Z","end":"2026-03-18T11:45:41.362734Z","steps":["trace[311904010] 'process raft request' (duration: 584.448887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.362818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.110935ms","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-18T11:45:41.362885Z","caller":"traceutil/trace.go:171","msg":"trace[1853245546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"633.224747ms","start":"2026-03-18T11:45:40.729641Z","end":"2026-03-18T11:45:41.362865Z","steps":["trace[1853245546] 'agreement among raft nodes before linearized reading' (duration: 633.034925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.362898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.250653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T11:45:41.362922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.729617Z","time spent":"633.298378ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T11:45:41.362928Z","caller":"traceutil/trace.go:171","msg":"trace[476762186] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"286.333354ms","start":"2026-03-18T11:45:41.076585Z","end":"2026-03-18T11:45:41.362918Z","steps":["trace[476762186] 'agreement among raft nodes before linearized reading' (duration: 286.270893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:41.36295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:40.778037Z","time spent":"584.830982ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:41.556501Z","caller":"traceutil/trace.go:171","msg":"trace[1689759639] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"189.315928ms","start":"2026-03-18T11:45:41.367152Z","end":"2026-03-18T11:45:41.556468Z","steps":["trace[1689759639] 'process raft request' (duration: 188.958753ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:41.55663Z","caller":"traceutil/trace.go:171","msg":"trace[408030551] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"147.883106ms","start":"2026-03-18T11:45:41.408706Z","end":"2026-03-18T11:45:41.55659Z","steps":["trace[408030551] 'read index received' (duration: 104.919286ms)","trace[408030551] 'applied index is now lower than readState.Index' (duration: 42.96183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:41.556741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.021908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:45:41.556506Z","caller":"traceutil/trace.go:171","msg":"trace[1180415571] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"270.428763ms","start":"2026-03-18T11:45:41.286043Z","end":"2026-03-18T11:45:41.556472Z","steps":["trace[1180415571] 'process raft request' (duration: 270.003247ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:41.556802Z","caller":"traceutil/trace.go:171","msg":"trace[1868882575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"148.134499ms","start":"2026-03-18T11:45:41.408659Z","end":"2026-03-18T11:45:41.556794Z","steps":["trace[1868882575] 'agreement among raft nodes before linearized reading' (duration: 148.025648ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:41.556792Z","caller":"traceutil/trace.go:171","msg":"trace[1414001617] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"189.609381ms","start":"2026-03-18T11:45:41.367171Z","end":"2026-03-18T11:45:41.55678Z","steps":["trace[1414001617] 'process raft request' (duration: 189.236777ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:41.557252Z","caller":"traceutil/trace.go:171","msg":"trace[481169409] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"278.227761ms","start":"2026-03-18T11:45:41.278986Z","end":"2026-03-18T11:45:41.557214Z","steps":["trace[481169409] 'process raft request' (duration: 234.622294ms)","trace[481169409] 'compare' (duration: 42.278922ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:43.854237Z","caller":"traceutil/trace.go:171","msg":"trace[544373249] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"567.783733ms","start":"2026-03-18T11:45:43.286422Z","end":"2026-03-18T11:45:43.854206Z","steps":["trace[544373249] 'process raft request' (duration: 567.616181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:43.854425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.286402Z","time spent":"567.929234ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:45:44.603683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.759842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:45:44.603959Z","caller":"traceutil/trace.go:171","msg":"trace[564850161] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"1.31675138s","start":"2026-03-18T11:45:43.287173Z","end":"2026-03-18T11:45:44.603924Z","steps":["trace[564850161] 'process raft request' (duration: 1.139655834s)","trace[564850161] 'compare' (duration: 176.628201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:44.604187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.287153Z","time spent":"1.316951632s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:44.627538Z","caller":"traceutil/trace.go:171","msg":"trace[112445643] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:444; }","duration":"1.217473328s","start":"2026-03-18T11:45:43.410023Z","end":"2026-03-18T11:45:44.627496Z","steps":["trace[112445643] 'read index received' (duration: 444.107767ms)","trace[112445643] 'applied index is now lower than readState.Index' (duration: 773.363661ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:44.627769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217738901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T11:45:44.627854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105688299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-03-18T11:45:44.627878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066119602s","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-18T11:45:44.627907Z","caller":"traceutil/trace.go:171","msg":"trace[966107168] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:429; }","duration":"1.10580517s","start":"2026-03-18T11:45:43.522087Z","end":"2026-03-18T11:45:44.627893Z","steps":["trace[966107168] 'agreement among raft nodes before linearized reading' (duration: 1.105631048s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:44.627936Z","caller":"traceutil/trace.go:171","msg":"trace[1713055339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"1.066215544s","start":"2026-03-18T11:45:43.561702Z","end":"2026-03-18T11:45:44.627917Z","steps":["trace[1713055339] 'agreement among raft nodes before linearized reading' (duration: 1.066119573s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:44.627958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.522058Z","time spent":"1.10589032s","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-03-18T11:45:44.62781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065293153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T11:45:44.627985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.561678Z","time spent":"1.066295704s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T11:45:44.627977Z","caller":"traceutil/trace.go:171","msg":"trace[1834567713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"1.217888363s","start":"2026-03-18T11:45:43.409981Z","end":"2026-03-18T11:45:44.627869Z","steps":["trace[1834567713] 'agreement among raft nodes before linearized reading' (duration: 1.217704191s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:44.628061Z","caller":"traceutil/trace.go:171","msg":"trace[1724590644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"1.065541117s","start":"2026-03-18T11:45:43.562474Z","end":"2026-03-18T11:45:44.628015Z","steps":["trace[1724590644] 'agreement among raft nodes before linearized reading' (duration: 1.065262453s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:44.628219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.562463Z","time spent":"1.065736799s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T11:45:44.628313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:43.409961Z","time spent":"1.218266688s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T11:45:44.776101Z","caller":"traceutil/trace.go:171","msg":"trace[1508503257] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"141.803601ms","start":"2026-03-18T11:45:44.634273Z","end":"2026-03-18T11:45:44.776076Z","steps":["trace[1508503257] 'process raft request' (duration: 141.589798ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:44.776268Z","caller":"traceutil/trace.go:171","msg":"trace[563363307] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:447; }","duration":"138.910607ms","start":"2026-03-18T11:45:44.637325Z","end":"2026-03-18T11:45:44.776236Z","steps":["trace[563363307] 'read index received' (duration: 67.728599ms)","trace[563363307] 'applied index is now lower than readState.Index' (duration: 71.179148ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:44.776307Z","caller":"traceutil/trace.go:171","msg":"trace[615398156] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"133.580998ms","start":"2026-03-18T11:45:44.642709Z","end":"2026-03-18T11:45:44.77629Z","steps":["trace[615398156] 'process raft request' (duration: 133.291475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:44.776433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.08485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:45:44.776506Z","caller":"traceutil/trace.go:171","msg":"trace[2122023337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"139.201502ms","start":"2026-03-18T11:45:44.637281Z","end":"2026-03-18T11:45:44.776482Z","steps":["trace[2122023337] 'agreement among raft nodes before linearized reading' (duration: 139.063679ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:45.012176Z","caller":"traceutil/trace.go:171","msg":"trace[130896760] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"104.618582ms","start":"2026-03-18T11:45:44.907533Z","end":"2026-03-18T11:45:45.012152Z","steps":["trace[130896760] 'read index received' (duration: 85.729341ms)","trace[130896760] 'applied index is now lower than readState.Index' (duration: 18.888511ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:45.012239Z","caller":"traceutil/trace.go:171","msg":"trace[719572205] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"105.057357ms","start":"2026-03-18T11:45:44.907152Z","end":"2026-03-18T11:45:45.01221Z","steps":["trace[719572205] 'process raft request' (duration: 86.093185ms)","trace[719572205] 'compare' (duration: 18.78777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:45.012307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.29555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-03-18T11:45:45.012339Z","caller":"traceutil/trace.go:171","msg":"trace[853171171] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:443; }","duration":"105.36708ms","start":"2026-03-18T11:45:44.906963Z","end":"2026-03-18T11:45:45.01233Z","steps":["trace[853171171] 'agreement among raft nodes before linearized reading' (duration: 105.277999ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:46.080912Z","caller":"traceutil/trace.go:171","msg":"trace[1537827932] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"216.908025ms","start":"2026-03-18T11:45:45.863974Z","end":"2026-03-18T11:45:46.080882Z","steps":["trace[1537827932] 'process raft request' (duration: 216.730353ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:46.874964Z","caller":"traceutil/trace.go:171","msg":"trace[342290778] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"165.89914ms","start":"2026-03-18T11:45:46.70903Z","end":"2026-03-18T11:45:46.874929Z","steps":["trace[342290778] 'read index received' (duration: 165.738688ms)","trace[342290778] 'applied index is now lower than readState.Index' (duration: 158.352µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:46.875132Z","caller":"traceutil/trace.go:171","msg":"trace[601896806] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"262.388991ms","start":"2026-03-18T11:45:46.612688Z","end":"2026-03-18T11:45:46.875077Z","steps":["trace[601896806] 'process raft request' (duration: 262.012467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:46.875245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.165154ms","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-18T11:45:46.875342Z","caller":"traceutil/trace.go:171","msg":"trace[1048786168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"166.307525ms","start":"2026-03-18T11:45:46.708986Z","end":"2026-03-18T11:45:46.875293Z","steps":["trace[1048786168] 'agreement among raft nodes before linearized reading' (duration: 166.158812ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:49.180864Z","caller":"traceutil/trace.go:171","msg":"trace[1064059071] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"288.781489ms","start":"2026-03-18T11:45:48.892012Z","end":"2026-03-18T11:45:49.180793Z","steps":["trace[1064059071] 'read index received' (duration: 288.473146ms)","trace[1064059071] 'applied index is now lower than readState.Index' (duration: 307.363µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:49.180958Z","caller":"traceutil/trace.go:171","msg":"trace[1595193461] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"297.867658ms","start":"2026-03-18T11:45:48.883031Z","end":"2026-03-18T11:45:49.180898Z","steps":["trace[1595193461] 'process raft request' (duration: 297.589105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:49.181061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.171281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T11:45:49.181074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.036962ms","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-18T11:45:49.181362Z","caller":"traceutil/trace.go:171","msg":"trace[1515489858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"289.357887ms","start":"2026-03-18T11:45:48.891987Z","end":"2026-03-18T11:45:49.181345Z","steps":["trace[1515489858] 'agreement among raft nodes before linearized reading' (duration: 289.007372ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:49.181118Z","caller":"traceutil/trace.go:171","msg":"trace[1496474053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"287.280353ms","start":"2026-03-18T11:45:48.89382Z","end":"2026-03-18T11:45:49.1811Z","steps":["trace[1496474053] 'agreement among raft nodes before linearized reading' (duration: 287.179032ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:51.457417Z","caller":"traceutil/trace.go:171","msg":"trace[1219576122] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"267.933526ms","start":"2026-03-18T11:45:51.18945Z","end":"2026-03-18T11:45:51.457383Z","steps":["trace[1219576122] 'process raft request' (duration: 267.737054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:51.457526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.637001ms","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-18T11:45:51.457648Z","caller":"traceutil/trace.go:171","msg":"trace[69664976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"240.821973ms","start":"2026-03-18T11:45:51.216813Z","end":"2026-03-18T11:45:51.457635Z","steps":["trace[69664976] 'agreement among raft nodes before linearized reading' (duration: 240.633511ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:51.457389Z","caller":"traceutil/trace.go:171","msg":"trace[986345694] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"240.468109ms","start":"2026-03-18T11:45:51.216863Z","end":"2026-03-18T11:45:51.457331Z","steps":["trace[986345694] 'read index received' (duration: 240.260917ms)","trace[986345694] 'applied index is now lower than readState.Index' (duration: 206.062µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:45:51.458506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.158687ms","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-18T11:45:51.458539Z","caller":"traceutil/trace.go:171","msg":"trace[1448264379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"217.223888ms","start":"2026-03-18T11:45:51.241304Z","end":"2026-03-18T11:45:51.458528Z","steps":["trace[1448264379] 'agreement among raft nodes before linearized reading' (duration: 217.157948ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:45:51.760502Z","caller":"traceutil/trace.go:171","msg":"trace[2046129613] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"297.637397ms","start":"2026-03-18T11:45:51.462832Z","end":"2026-03-18T11:45:51.760469Z","steps":["trace[2046129613] 'process raft request' (duration: 203.757443ms)","trace[2046129613] 'compare' (duration: 93.748643ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:51.801791Z","caller":"traceutil/trace.go:171","msg":"trace[1599185681] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"338.825362ms","start":"2026-03-18T11:45:51.462932Z","end":"2026-03-18T11:45:51.801758Z","steps":["trace[1599185681] 'process raft request' (duration: 338.6318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:45:51.802043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:51.462898Z","time spent":"338.989005ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:45:51.906189Z","caller":"traceutil/trace.go:171","msg":"trace[1856342727] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"179.216117ms","start":"2026-03-18T11:45:51.726934Z","end":"2026-03-18T11:45:51.90615Z","steps":["trace[1856342727] 'process raft request' (duration: 149.953381ms)","trace[1856342727] 'compare' (duration: 29.117295ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:45:54.044471Z","caller":"traceutil/trace.go:171","msg":"trace[615457419] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"221.192046ms","start":"2026-03-18T11:45:53.823252Z","end":"2026-03-18T11:45:54.044444Z","steps":["trace[615457419] 'process raft request' (duration: 192.094436ms)","trace[615457419] 'compare' (duration: 28.958908ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:00.536329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:00.929021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052898025s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T11:46:00.929553Z","caller":"traceutil/trace.go:171","msg":"trace[748307346] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"894.044214ms","start":"2026-03-18T11:46:00.035474Z","end":"2026-03-18T11:46:00.929519Z","steps":["trace[748307346] 'read index received' (duration: 893.915743ms)","trace[748307346] 'applied index is now lower than readState.Index' (duration: 126.971µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:00.929657Z","caller":"traceutil/trace.go:171","msg":"trace[968546366] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.053595153s","start":"2026-03-18T11:45:59.876016Z","end":"2026-03-18T11:46:00.929611Z","steps":["trace[968546366] 'process raft request' (duration: 1.053301509s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.929916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:45:59.876Z","time spent":"1.053789595s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:00.929968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.450809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-18T11:46:00.930102Z","caller":"traceutil/trace.go:171","msg":"trace[2033752107] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:526; }","duration":"894.672772ms","start":"2026-03-18T11:46:00.035399Z","end":"2026-03-18T11:46:00.930072Z","steps":["trace[2033752107] 'agreement among raft nodes before linearized reading' (duration: 894.292518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.930166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:00.035368Z","time spent":"894.787712ms","remote":"127.0.0.1:39676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-18T11:46:00.943628Z","caller":"traceutil/trace.go:171","msg":"trace[469688314] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"101.044186ms","start":"2026-03-18T11:46:00.842556Z","end":"2026-03-18T11:46:00.9436Z","steps":["trace[469688314] 'process raft request' (duration: 100.804624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.943689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.404642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:00.943771Z","caller":"traceutil/trace.go:171","msg":"trace[613260758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"532.548154ms","start":"2026-03-18T11:46:00.411204Z","end":"2026-03-18T11:46:00.943752Z","steps":["trace[613260758] 'agreement among raft nodes before linearized reading' (duration: 532.403602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.943797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.294829ms","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-18T11:46:00.943864Z","caller":"traceutil/trace.go:171","msg":"trace[1950848348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"839.41023ms","start":"2026-03-18T11:46:00.104441Z","end":"2026-03-18T11:46:00.943851Z","steps":["trace[1950848348] 'agreement among raft nodes before linearized reading' (duration: 839.051785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.943857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.759334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-18T11:46:00.943909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:00.104415Z","time spent":"839.48629ms","remote":"127.0.0.1:39790","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-18T11:46:00.943811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:00.411175Z","time spent":"532.629334ms","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T11:46:00.943929Z","caller":"traceutil/trace.go:171","msg":"trace[686601724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"699.919956ms","start":"2026-03-18T11:46:00.24399Z","end":"2026-03-18T11:46:00.94391Z","steps":["trace[686601724] 'agreement among raft nodes before linearized reading' (duration: 699.729284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:00.944077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:00.243958Z","time spent":"700.062387ms","remote":"127.0.0.1:39790","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-18T11:46:01.776701Z","caller":"traceutil/trace.go:171","msg":"trace[532103179] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"210.61044ms","start":"2026-03-18T11:46:01.566061Z","end":"2026-03-18T11:46:01.776671Z","steps":["trace[532103179] 'process raft request' (duration: 210.396078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:02.911242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:03.411659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:03.504881Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.393357471s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T11:46:03.580802Z","caller":"traceutil/trace.go:171","msg":"trace[2125537107] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"1.169987936s","start":"2026-03-18T11:46:02.410787Z","end":"2026-03-18T11:46:03.580775Z","steps":["trace[2125537107] 'read index received' (duration: 1.094260443s)","trace[2125537107] 'applied index is now lower than readState.Index' (duration: 75.726193ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:03.580925Z","caller":"traceutil/trace.go:171","msg":"trace[1838367940] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.469484528s","start":"2026-03-18T11:46:02.111428Z","end":"2026-03-18T11:46:03.580912Z","steps":["trace[1838367940] 'process raft request' (duration: 1.393784465s)","trace[1838367940] 'compare' (duration: 74.616151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:03.581299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.02594ms","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-18T11:46:03.581342Z","caller":"traceutil/trace.go:171","msg":"trace[1842006007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"645.128061ms","start":"2026-03-18T11:46:02.9362Z","end":"2026-03-18T11:46:03.581328Z","steps":["trace[1842006007] 'agreement among raft nodes before linearized reading' (duration: 644.951129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:03.581371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:02.936166Z","time spent":"645.197692ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T11:46:03.581639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.953373ms","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-18T11:46:03.581668Z","caller":"traceutil/trace.go:171","msg":"trace[1592832447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"575.002493ms","start":"2026-03-18T11:46:03.006657Z","end":"2026-03-18T11:46:03.581659Z","steps":["trace[1592832447] 'agreement among raft nodes before linearized reading' (duration: 574.914682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:03.581696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:03.006646Z","time spent":"575.043503ms","remote":"127.0.0.1:39790","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-18T11:46:03.581762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.402157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T11:46:03.581859Z","caller":"traceutil/trace.go:171","msg":"trace[1047017877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"574.566718ms","start":"2026-03-18T11:46:03.007268Z","end":"2026-03-18T11:46:03.581835Z","steps":["trace[1047017877] 'agreement among raft nodes before linearized reading' (duration: 574.299666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:03.581907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:03.007239Z","time spent":"574.65927ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T11:46:03.581916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171111358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:03.582014Z","caller":"traceutil/trace.go:171","msg":"trace[1105479323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.171223789s","start":"2026-03-18T11:46:02.410764Z","end":"2026-03-18T11:46:03.581988Z","steps":["trace[1105479323] 'agreement among raft nodes before linearized reading' (duration: 1.171043997s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:03.582062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:02.111399Z","time spent":"1.469554979s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:03.58206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:02.410681Z","time spent":"1.171370431s","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T11:46:03.833188Z","caller":"traceutil/trace.go:171","msg":"trace[196364500] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"246.410247ms","start":"2026-03-18T11:46:03.586745Z","end":"2026-03-18T11:46:03.833156Z","steps":["trace[196364500] 'process raft request' (duration: 152.174705ms)","trace[196364500] 'compare' (duration: 94.02994ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:03.833504Z","caller":"traceutil/trace.go:171","msg":"trace[436290607] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"246.432447ms","start":"2026-03-18T11:46:03.587057Z","end":"2026-03-18T11:46:03.83349Z","steps":["trace[436290607] 'process raft request' (duration: 246.056882ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:03.833629Z","caller":"traceutil/trace.go:171","msg":"trace[2026724980] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:554; }","duration":"241.209754ms","start":"2026-03-18T11:46:03.592395Z","end":"2026-03-18T11:46:03.833605Z","steps":["trace[2026724980] 'read index received' (duration: 146.514156ms)","trace[2026724980] 'applied index is now lower than readState.Index' (duration: 94.694068ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:03.833768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.326195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:03.833825Z","caller":"traceutil/trace.go:171","msg":"trace[1165671828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"241.419466ms","start":"2026-03-18T11:46:03.592392Z","end":"2026-03-18T11:46:03.833811Z","steps":["trace[1165671828] 'agreement among raft nodes before linearized reading' (duration: 241.298685ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:03.833731Z","caller":"traceutil/trace.go:171","msg":"trace[1462459099] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"246.295125ms","start":"2026-03-18T11:46:03.587342Z","end":"2026-03-18T11:46:03.833637Z","steps":["trace[1462459099] 'process raft request' (duration: 246.139614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:03.884766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.587598ms","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-18T11:46:03.884883Z","caller":"traceutil/trace.go:171","msg":"trace[1638206039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"102.762789ms","start":"2026-03-18T11:46:03.782094Z","end":"2026-03-18T11:46:03.884857Z","steps":["trace[1638206039] 'agreement among raft nodes before linearized reading' (duration: 102.414096ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:06.215506Z","caller":"traceutil/trace.go:171","msg":"trace[208670918] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"373.105649ms","start":"2026-03-18T11:46:05.842373Z","end":"2026-03-18T11:46:06.215479Z","steps":["trace[208670918] 'process raft request' (duration: 372.846016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:06.215721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:05.842352Z","time spent":"373.24639ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:06.216221Z","caller":"traceutil/trace.go:171","msg":"trace[242308237] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:559; }","duration":"267.421068ms","start":"2026-03-18T11:46:05.948774Z","end":"2026-03-18T11:46:06.216195Z","steps":["trace[242308237] 'read index received' (duration: 266.441459ms)","trace[242308237] 'applied index is now lower than readState.Index' (duration: 978.539µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:06.216285Z","caller":"traceutil/trace.go:171","msg":"trace[1625333113] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"373.679334ms","start":"2026-03-18T11:46:05.842588Z","end":"2026-03-18T11:46:06.216267Z","steps":["trace[1625333113] 'process raft request' (duration: 373.466172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:06.216375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:05.842576Z","time spent":"373.743224ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:06.216405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.61803ms","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-18T11:46:06.216445Z","caller":"traceutil/trace.go:171","msg":"trace[415579099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"267.727012ms","start":"2026-03-18T11:46:05.948706Z","end":"2026-03-18T11:46:06.216433Z","steps":["trace[415579099] 'agreement among raft nodes before linearized reading' (duration: 267.616831ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:06.216344Z","caller":"traceutil/trace.go:171","msg":"trace[580463250] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"371.887925ms","start":"2026-03-18T11:46:05.844401Z","end":"2026-03-18T11:46:06.216289Z","steps":["trace[580463250] 'process raft request' (duration: 371.757514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:06.216844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:05.84439Z","time spent":"372.208819ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:11.191088Z","caller":"traceutil/trace.go:171","msg":"trace[216141770] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"163.13016ms","start":"2026-03-18T11:46:11.027932Z","end":"2026-03-18T11:46:11.191062Z","steps":["trace[216141770] 'process raft request' (duration: 162.994599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.072258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.025389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqbhb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-18T11:46:13.072343Z","caller":"traceutil/trace.go:171","msg":"trace[528845102] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqbhb; range_end:; response_count:1; response_revision:575; }","duration":"593.166292ms","start":"2026-03-18T11:46:12.479159Z","end":"2026-03-18T11:46:13.072326Z","steps":["trace[528845102] 'range keys from in-memory index tree' (duration: 592.857218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.072374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.479132Z","time spent":"593.235062ms","remote":"127.0.0.1:39692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gqbhb\" "} {"level":"warn","ts":"2026-03-18T11:46:13.072439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.668189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T11:46:13.072541Z","caller":"traceutil/trace.go:171","msg":"trace[745598651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"753.907152ms","start":"2026-03-18T11:46:12.318612Z","end":"2026-03-18T11:46:13.072519Z","steps":["trace[745598651] 'range keys from in-memory index tree' (duration: 753.422047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.072595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.318578Z","time spent":"754.003082ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T11:46:13.072601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.707454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:13.072681Z","caller":"traceutil/trace.go:171","msg":"trace[132628510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"661.851986ms","start":"2026-03-18T11:46:12.410819Z","end":"2026-03-18T11:46:13.072671Z","steps":["trace[132628510] 'range keys from in-memory index tree' (duration: 661.613593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.07273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.410788Z","time spent":"661.907746ms","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T11:46:13.072744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.892266ms","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-18T11:46:13.072768Z","caller":"traceutil/trace.go:171","msg":"trace[2081365114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"786.958427ms","start":"2026-03-18T11:46:12.285803Z","end":"2026-03-18T11:46:13.072761Z","steps":["trace[2081365114] 'range keys from in-memory index tree' (duration: 786.660733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.072786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.285776Z","time spent":"787.005537ms","remote":"127.0.0.1:39790","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-18T11:46:13.072866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.672221ms","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-18T11:46:13.072892Z","caller":"traceutil/trace.go:171","msg":"trace[235203288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"748.742321ms","start":"2026-03-18T11:46:12.324142Z","end":"2026-03-18T11:46:13.072884Z","steps":["trace[235203288] 'range keys from in-memory index tree' (duration: 748.519008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.072917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.324118Z","time spent":"748.793591ms","remote":"127.0.0.1:39790","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-18T11:46:13.072917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.665851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T11:46:13.073016Z","caller":"traceutil/trace.go:171","msg":"trace[1869005042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"748.848772ms","start":"2026-03-18T11:46:12.324142Z","end":"2026-03-18T11:46:13.07299Z","steps":["trace[1869005042] 'range keys from in-memory index tree' (duration: 748.398777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.073066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:12.324125Z","time spent":"748.926682ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T11:46:13.931772Z","caller":"traceutil/trace.go:171","msg":"trace[504353308] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"855.012345ms","start":"2026-03-18T11:46:13.076725Z","end":"2026-03-18T11:46:13.931738Z","steps":["trace[504353308] 'process raft request' (duration: 854.811583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:13.931967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.07671Z","time spent":"855.160486ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:14.91065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:15.411392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:15.912564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:16.41038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999811854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T11:46:16.410529Z","caller":"traceutil/trace.go:171","msg":"trace[898466547] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000030746s","start":"2026-03-18T11:46:14.410466Z","end":"2026-03-18T11:46:16.410497Z","steps":["trace[898466547] 'agreement among raft nodes before linearized reading' (duration: 1.999858344s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:16.410611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:14.410436Z","time spent":"2.000157238s","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T11:46:16.412905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302477436606961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T11:46:16.506817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.575224414s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T11:46:16.507329Z","caller":"traceutil/trace.go:171","msg":"trace[580605564] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"3.430556202s","start":"2026-03-18T11:46:13.076747Z","end":"2026-03-18T11:46:16.507303Z","steps":["trace[580605564] 'process raft request' (duration: 3.43027982s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:16.507362Z","caller":"traceutil/trace.go:171","msg":"trace[281968147] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"3.429809974s","start":"2026-03-18T11:46:13.077526Z","end":"2026-03-18T11:46:16.507335Z","steps":["trace[281968147] 'process raft request' (duration: 3.429685573s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:16.507495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.077518Z","time spent":"3.429902666s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:16.507593Z","caller":"traceutil/trace.go:171","msg":"trace[508499916] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"2.908748732s","start":"2026-03-18T11:46:13.598837Z","end":"2026-03-18T11:46:16.507585Z","steps":["trace[508499916] 'process raft request' (duration: 2.90860991s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:16.507632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.598798Z","time spent":"2.908815832s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:16.507374Z","caller":"traceutil/trace.go:171","msg":"trace[1568683461] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"3.430390631s","start":"2026-03-18T11:46:13.076921Z","end":"2026-03-18T11:46:16.507312Z","steps":["trace[1568683461] 'process raft request' (duration: 3.43024897s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:16.507564Z","caller":"traceutil/trace.go:171","msg":"trace[80183463] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"3.420554324s","start":"2026-03-18T11:46:13.086978Z","end":"2026-03-18T11:46:16.507532Z","steps":["trace[80183463] 'process raft request' (duration: 3.420277201s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:16.50782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.076716Z","time spent":"3.430678204s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:16.507821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.076913Z","time spent":"3.430827096s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:16.508152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:13.086961Z","time spent":"3.420890747s","remote":"127.0.0.1:39692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:18.411286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00029508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-18T11:46:18.4114Z","caller":"traceutil/trace.go:171","msg":"trace[2928559] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000464382s","start":"2026-03-18T11:46:16.410914Z","end":"2026-03-18T11:46:18.411378Z","steps":["trace[2928559] 'agreement among raft nodes before linearized reading' (duration: 2.000319891s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:18.411454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:16.410895Z","time spent":"2.000548993s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/18 11:46:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T11:46:20.228801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.72128073s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-18T11:46:20.228898Z","caller":"traceutil/trace.go:171","msg":"trace[1630827026] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:600; }","duration":"5.818343018s","start":"2026-03-18T11:46:14.410537Z","end":"2026-03-18T11:46:20.22888Z","steps":["trace[1630827026] 'read index received' (duration: 2.09642266s)","trace[1630827026] 'applied index is now lower than readState.Index' (duration: 3.721918898s)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:20.229412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717605585s","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-18T11:46:20.229461Z","caller":"traceutil/trace.go:171","msg":"trace[1237064485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.717677135s","start":"2026-03-18T11:46:18.511773Z","end":"2026-03-18T11:46:20.22945Z","steps":["trace[1237064485] 'agreement among raft nodes before linearized reading' (duration: 1.717583234s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718198261s","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-18T11:46:20.229462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.522307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T11:46:20.229463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062116152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T11:46:20.229377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.177664ms","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-18T11:46:20.22953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80967601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:20.229539Z","caller":"traceutil/trace.go:171","msg":"trace[367825934] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:581; }","duration":"447.645089ms","start":"2026-03-18T11:46:19.781874Z","end":"2026-03-18T11:46:20.229519Z","steps":["trace[367825934] 'agreement among raft nodes before linearized reading' (duration: 447.524768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110220717s","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-18T11:46:20.229539Z","caller":"traceutil/trace.go:171","msg":"trace[1014938752] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:581; }","duration":"1.062234283s","start":"2026-03-18T11:46:19.167291Z","end":"2026-03-18T11:46:20.229525Z","steps":["trace[1014938752] 'agreement among raft nodes before linearized reading' (duration: 1.062110952s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.229571Z","caller":"traceutil/trace.go:171","msg":"trace[470802224] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:581; }","duration":"2.110280588s","start":"2026-03-18T11:46:18.119285Z","end":"2026-03-18T11:46:20.229566Z","steps":["trace[470802224] 'agreement among raft nodes before linearized reading' (duration: 2.110258588s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:19.781854Z","time spent":"447.717269ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-18T11:46:20.229597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.119253Z","time spent":"2.110340199s","remote":"127.0.0.1:40026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-18T11:46:20.2296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871908088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-18T11:46:20.229602Z","caller":"traceutil/trace.go:171","msg":"trace[1918277438] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:581; }","duration":"704.387066ms","start":"2026-03-18T11:46:19.525152Z","end":"2026-03-18T11:46:20.229539Z","steps":["trace[1918277438] 'range keys from in-memory index tree' (duration: 704.153334ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.229636Z","caller":"traceutil/trace.go:171","msg":"trace[356995344] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:581; }","duration":"1.871983339s","start":"2026-03-18T11:46:18.357642Z","end":"2026-03-18T11:46:20.229625Z","steps":["trace[356995344] 'agreement among raft nodes before linearized reading' (duration: 1.871883868s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.357617Z","time spent":"1.872042989s","remote":"127.0.0.1:35090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-03-18T11:46:20.229681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717704826s","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-18T11:46:20.229505Z","caller":"traceutil/trace.go:171","msg":"trace[958647129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.718247031s","start":"2026-03-18T11:46:18.511251Z","end":"2026-03-18T11:46:20.229498Z","steps":["trace[958647129] 'agreement among raft nodes before linearized reading' (duration: 1.7181953s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.511231Z","time spent":"1.718548123s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T11:46:20.229504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.511766Z","time spent":"1.717730476s","remote":"127.0.0.1:39790","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":"info","ts":"2026-03-18T11:46:20.229786Z","caller":"traceutil/trace.go:171","msg":"trace[2135042695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"1.717823957s","start":"2026-03-18T11:46:18.51192Z","end":"2026-03-18T11:46:20.229744Z","steps":["trace[2135042695] 'agreement among raft nodes before linearized reading' (duration: 1.717543074s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.229827Z","caller":"traceutil/trace.go:171","msg":"trace[1722400737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"1.809730581s","start":"2026-03-18T11:46:18.41981Z","end":"2026-03-18T11:46:20.229541Z","steps":["trace[1722400737] 'agreement among raft nodes before linearized reading' (duration: 1.809706551s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.229895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.419779Z","time spent":"1.810097614s","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T11:46:20.229896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.511896Z","time spent":"1.717973288s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T11:46:20.229431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.289131062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T11:46:20.229965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814738218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189deceebf7b0eb9\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-18T11:46:20.229986Z","caller":"traceutil/trace.go:171","msg":"trace[864372908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"4.289724938s","start":"2026-03-18T11:46:15.940248Z","end":"2026-03-18T11:46:20.229973Z","steps":["trace[864372908] 'agreement among raft nodes before linearized reading' (duration: 4.289101772s)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.230006Z","caller":"traceutil/trace.go:171","msg":"trace[705741951] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189deceebf7b0eb9; range_end:; response_count:1; response_revision:581; }","duration":"1.814797909s","start":"2026-03-18T11:46:18.415194Z","end":"2026-03-18T11:46:20.229992Z","steps":["trace[705741951] 'agreement among raft nodes before linearized reading' (duration: 1.814652758s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.230027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:15.940211Z","time spent":"4.289804788s","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T11:46:20.230037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:18.415184Z","time spent":"1.814846829s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189deceebf7b0eb9\" "} {"level":"warn","ts":"2026-03-18T11:46:20.229585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:19.16727Z","time spent":"1.062305773s","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-03-18T11:46:20.509867Z","caller":"traceutil/trace.go:171","msg":"trace[801307706] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"275.373378ms","start":"2026-03-18T11:46:20.234461Z","end":"2026-03-18T11:46:20.509834Z","steps":["trace[801307706] 'process raft request' (duration: 274.932284ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.50988Z","caller":"traceutil/trace.go:171","msg":"trace[888706935] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"276.747499ms","start":"2026-03-18T11:46:20.233105Z","end":"2026-03-18T11:46:20.509853Z","steps":["trace[888706935] 'read index received' (duration: 42.175692ms)","trace[888706935] 'applied index is now lower than readState.Index' (duration: 234.570577ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:20.509982Z","caller":"traceutil/trace.go:171","msg":"trace[2081720599] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"275.099083ms","start":"2026-03-18T11:46:20.234834Z","end":"2026-03-18T11:46:20.509933Z","steps":["trace[2081720599] 'process raft request' (duration: 274.860491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.510062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.921901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:20.510055Z","caller":"traceutil/trace.go:171","msg":"trace[1171921474] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"275.014413ms","start":"2026-03-18T11:46:20.234995Z","end":"2026-03-18T11:46:20.510009Z","steps":["trace[1171921474] 'process raft request' (duration: 274.762741ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.510108Z","caller":"traceutil/trace.go:171","msg":"trace[200497439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"277.022192ms","start":"2026-03-18T11:46:20.23307Z","end":"2026-03-18T11:46:20.510092Z","steps":["trace[200497439] 'agreement among raft nodes before linearized reading' (duration: 276.921571ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.510153Z","caller":"traceutil/trace.go:171","msg":"trace[154001353] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"275.346496ms","start":"2026-03-18T11:46:20.234752Z","end":"2026-03-18T11:46:20.510098Z","steps":["trace[154001353] 'process raft request' (duration: 274.879742ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.539365Z","caller":"traceutil/trace.go:171","msg":"trace[1228272200] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"262.564564ms","start":"2026-03-18T11:46:20.276779Z","end":"2026-03-18T11:46:20.539344Z","steps":["trace[1228272200] 'process raft request' (duration: 262.386362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:20.539458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.749679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:20.539536Z","caller":"traceutil/trace.go:171","msg":"trace[707890877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"256.89961ms","start":"2026-03-18T11:46:20.282618Z","end":"2026-03-18T11:46:20.539518Z","steps":["trace[707890877] 'agreement among raft nodes before linearized reading' (duration: 256.721749ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.683928Z","caller":"traceutil/trace.go:171","msg":"trace[655115087] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"109.632596ms","start":"2026-03-18T11:46:20.574265Z","end":"2026-03-18T11:46:20.683897Z","steps":["trace[655115087] 'process raft request' (duration: 109.454564ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:20.70358Z","caller":"traceutil/trace.go:171","msg":"trace[971267839] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"116.882186ms","start":"2026-03-18T11:46:20.586682Z","end":"2026-03-18T11:46:20.703564Z","steps":["trace[971267839] 'process raft request' (duration: 116.776684ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:21.385483Z","caller":"traceutil/trace.go:171","msg":"trace[721205117] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"266.432593ms","start":"2026-03-18T11:46:21.119024Z","end":"2026-03-18T11:46:21.385456Z","steps":["trace[721205117] 'process raft request' (duration: 266.299901ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:22.464306Z","caller":"traceutil/trace.go:171","msg":"trace[406366913] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"506.105145ms","start":"2026-03-18T11:46:21.958183Z","end":"2026-03-18T11:46:22.464288Z","steps":["trace[406366913] 'process raft request' (duration: 505.736271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:22.464428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:21.958163Z","time spent":"506.193895ms","remote":"127.0.0.1:39676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:22.65188Z","caller":"traceutil/trace.go:171","msg":"trace[1977748835] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"132.420774ms","start":"2026-03-18T11:46:22.519438Z","end":"2026-03-18T11:46:22.651859Z","steps":["trace[1977748835] 'process raft request' (duration: 132.213373ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:22.652193Z","caller":"traceutil/trace.go:171","msg":"trace[1362276587] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"130.443485ms","start":"2026-03-18T11:46:22.521711Z","end":"2026-03-18T11:46:22.652155Z","steps":["trace[1362276587] 'process raft request' (duration: 130.113592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:22.65241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.661387ms","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-18T11:46:22.652479Z","caller":"traceutil/trace.go:171","msg":"trace[943743712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"131.793758ms","start":"2026-03-18T11:46:22.52067Z","end":"2026-03-18T11:46:22.652464Z","steps":["trace[943743712] 'agreement among raft nodes before linearized reading' (duration: 131.537385ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:22.652064Z","caller":"traceutil/trace.go:171","msg":"trace[1244499344] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"131.347144ms","start":"2026-03-18T11:46:22.520693Z","end":"2026-03-18T11:46:22.65204Z","steps":["trace[1244499344] 'read index received' (duration: 7.443031ms)","trace[1244499344] 'applied index is now lower than readState.Index' (duration: 123.902523ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:22.838252Z","caller":"traceutil/trace.go:171","msg":"trace[532738759] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"181.728931ms","start":"2026-03-18T11:46:22.656488Z","end":"2026-03-18T11:46:22.838217Z","steps":["trace[532738759] 'process raft request' (duration: 82.901006ms)","trace[532738759] 'compare' (duration: 98.592033ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:23.262918Z","caller":"traceutil/trace.go:171","msg":"trace[2053927060] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"281.106493ms","start":"2026-03-18T11:46:22.981796Z","end":"2026-03-18T11:46:23.262902Z","steps":["trace[2053927060] 'process raft request' (duration: 281.004202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:24.978082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.328879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:46:24.978321Z","caller":"traceutil/trace.go:171","msg":"trace[2016553469] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"322.582835ms","start":"2026-03-18T11:46:24.655715Z","end":"2026-03-18T11:46:24.978298Z","steps":["trace[2016553469] 'read index received' (duration: 47.126144ms)","trace[2016553469] 'applied index is now lower than readState.Index' (duration: 275.455731ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:24.978391Z","caller":"traceutil/trace.go:171","msg":"trace[1748121039] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"341.21598ms","start":"2026-03-18T11:46:24.637144Z","end":"2026-03-18T11:46:24.97836Z","steps":["trace[1748121039] 'process raft request' (duration: 65.534126ms)","trace[1748121039] 'compare' (duration: 275.109108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:24.978495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:24.637116Z","time spent":"341.3431ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T11:46:24.978636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.903748ms","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-18T11:46:24.978695Z","caller":"traceutil/trace.go:171","msg":"trace[797545617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"322.990888ms","start":"2026-03-18T11:46:24.655691Z","end":"2026-03-18T11:46:24.978682Z","steps":["trace[797545617] 'agreement among raft nodes before linearized reading' (duration: 322.800067ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:24.978419Z","caller":"traceutil/trace.go:171","msg":"trace[1754119789] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"327.701862ms","start":"2026-03-18T11:46:24.650705Z","end":"2026-03-18T11:46:24.978407Z","steps":["trace[1754119789] 'process raft request' (duration: 327.51104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:24.978731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:24.655684Z","time spent":"323.037259ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T11:46:24.978789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:24.650684Z","time spent":"328.055346ms","remote":"127.0.0.1:39692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17771,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:46:24.979039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.258841ms","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-18T11:46:24.979147Z","caller":"traceutil/trace.go:171","msg":"trace[184435924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"323.394112ms","start":"2026-03-18T11:46:24.65573Z","end":"2026-03-18T11:46:24.979124Z","steps":["trace[184435924] 'agreement among raft nodes before linearized reading' (duration: 323.082209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:24.979236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:24.65572Z","time spent":"323.504453ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T11:46:24.979084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.299232ms","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-18T11:46:24.979378Z","caller":"traceutil/trace.go:171","msg":"trace[1329619060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"136.630664ms","start":"2026-03-18T11:46:24.842724Z","end":"2026-03-18T11:46:24.979355Z","steps":["trace[1329619060] 'agreement among raft nodes before linearized reading' (duration: 136.10485ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:25.825354Z","caller":"traceutil/trace.go:171","msg":"trace[1654162450] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"612.945456ms","start":"2026-03-18T11:46:25.212383Z","end":"2026-03-18T11:46:25.825328Z","steps":["trace[1654162450] 'process raft request' (duration: 579.769514ms)","trace[1654162450] 'compare' (duration: 32.736727ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:25.825479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:25.212369Z","time spent":"613.049906ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T11:46:26.35504Z","caller":"traceutil/trace.go:171","msg":"trace[332095371] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"272.099791ms","start":"2026-03-18T11:46:26.082911Z","end":"2026-03-18T11:46:26.355011Z","steps":["trace[332095371] 'process raft request' (duration: 271.767428ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:26.569453Z","caller":"traceutil/trace.go:171","msg":"trace[1790124652] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"159.347629ms","start":"2026-03-18T11:46:26.41008Z","end":"2026-03-18T11:46:26.569428Z","steps":["trace[1790124652] 'read index received' (duration: 92.818708ms)","trace[1790124652] 'applied index is now lower than readState.Index' (duration: 66.527781ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:26.569573Z","caller":"traceutil/trace.go:171","msg":"trace[397353489] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"208.231916ms","start":"2026-03-18T11:46:26.361296Z","end":"2026-03-18T11:46:26.569527Z","steps":["trace[397353489] 'process raft request' (duration: 141.641654ms)","trace[397353489] 'compare' (duration: 66.265449ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:26.569629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.52845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:26.569704Z","caller":"traceutil/trace.go:171","msg":"trace[1228510763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"159.659301ms","start":"2026-03-18T11:46:26.410022Z","end":"2026-03-18T11:46:26.569681Z","steps":["trace[1228510763] 'agreement among raft nodes before linearized reading' (duration: 159.50884ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:26.894602Z","caller":"traceutil/trace.go:171","msg":"trace[25862016] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"317.9702ms","start":"2026-03-18T11:46:26.576601Z","end":"2026-03-18T11:46:26.894571Z","steps":["trace[25862016] 'process raft request' (duration: 261.047249ms)","trace[25862016] 'compare' (duration: 56.73121ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:26.894793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:26.576569Z","time spent":"318.116341ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:26.951859Z","caller":"traceutil/trace.go:171","msg":"trace[1940738349] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"269.681109ms","start":"2026-03-18T11:46:26.68214Z","end":"2026-03-18T11:46:26.951821Z","steps":["trace[1940738349] 'process raft request' (duration: 269.384197ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:26.952502Z","caller":"traceutil/trace.go:171","msg":"trace[1632472687] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"136.658027ms","start":"2026-03-18T11:46:26.815567Z","end":"2026-03-18T11:46:26.952225Z","steps":["trace[1632472687] 'process raft request' (duration: 136.118532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:27.679154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.597896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:46:27.679367Z","caller":"traceutil/trace.go:171","msg":"trace[836227585] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"313.060186ms","start":"2026-03-18T11:46:27.366265Z","end":"2026-03-18T11:46:27.679325Z","steps":["trace[836227585] 'process raft request' (duration: 108.145587ms)","trace[836227585] 'compare' (duration: 204.414253ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:27.679527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:27.366213Z","time spent":"313.220447ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:27.679442Z","caller":"traceutil/trace.go:171","msg":"trace[793152499] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"270.182745ms","start":"2026-03-18T11:46:27.409228Z","end":"2026-03-18T11:46:27.67941Z","steps":["trace[793152499] 'read index received' (duration: 65.173136ms)","trace[793152499] 'applied index is now lower than readState.Index' (duration: 205.007669ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:27.679664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.378457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:27.679739Z","caller":"traceutil/trace.go:171","msg":"trace[927656396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"270.535939ms","start":"2026-03-18T11:46:27.409182Z","end":"2026-03-18T11:46:27.679718Z","steps":["trace[927656396] 'agreement among raft nodes before linearized reading' (duration: 270.367658ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:27.67988Z","caller":"traceutil/trace.go:171","msg":"trace[428566892] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"194.586421ms","start":"2026-03-18T11:46:27.485265Z","end":"2026-03-18T11:46:27.679851Z","steps":["trace[428566892] 'process raft request' (duration: 194.088857ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:28.353289Z","caller":"traceutil/trace.go:171","msg":"trace[2048902037] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"237.11939ms","start":"2026-03-18T11:46:28.116116Z","end":"2026-03-18T11:46:28.353235Z","steps":["trace[2048902037] 'process raft request' (duration: 235.937399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:28.836745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.242513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:46:28.836886Z","caller":"traceutil/trace.go:171","msg":"trace[1428383279] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"397.65858ms","start":"2026-03-18T11:46:28.439207Z","end":"2026-03-18T11:46:28.836865Z","steps":["trace[1428383279] 'process raft request' (duration: 218.145714ms)","trace[1428383279] 'compare' (duration: 178.91662ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:28.836952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:28.439188Z","time spent":"397.733081ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T11:46:28.851929Z","caller":"traceutil/trace.go:171","msg":"trace[1496759080] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"236.576795ms","start":"2026-03-18T11:46:28.615307Z","end":"2026-03-18T11:46:28.851884Z","steps":["trace[1496759080] 'read index received' (duration: 41.689587ms)","trace[1496759080] 'applied index is now lower than readState.Index' (duration: 194.886038ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:28.852241Z","caller":"traceutil/trace.go:171","msg":"trace[1854544954] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"338.612772ms","start":"2026-03-18T11:46:28.513619Z","end":"2026-03-18T11:46:28.852232Z","steps":["trace[1854544954] 'process raft request' (duration: 338.124427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:28.852544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:46:28.513601Z","time spent":"338.666432ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T11:46:28.852605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.278702ms","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-18T11:46:28.852677Z","caller":"traceutil/trace.go:171","msg":"trace[234183213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"237.400994ms","start":"2026-03-18T11:46:28.615263Z","end":"2026-03-18T11:46:28.852664Z","steps":["trace[234183213] 'agreement among raft nodes before linearized reading' (duration: 237.160082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:28.852742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.464492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-18T11:46:28.852848Z","caller":"traceutil/trace.go:171","msg":"trace[1492746754] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:666; }","duration":"169.626344ms","start":"2026-03-18T11:46:28.683196Z","end":"2026-03-18T11:46:28.852822Z","steps":["trace[1492746754] 'agreement among raft nodes before linearized reading' (duration: 169.304321ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.248701Z","caller":"traceutil/trace.go:171","msg":"trace[998514780] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"216.50387ms","start":"2026-03-18T11:46:31.032174Z","end":"2026-03-18T11:46:31.248678Z","steps":["trace[998514780] 'read index received' (duration: 216.274579ms)","trace[998514780] 'applied index is now lower than readState.Index' (duration: 228.051µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:31.248788Z","caller":"traceutil/trace.go:171","msg":"trace[170550499] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"242.576849ms","start":"2026-03-18T11:46:31.00617Z","end":"2026-03-18T11:46:31.248747Z","steps":["trace[170550499] 'process raft request' (duration: 242.339237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.248858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.657502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.248904Z","caller":"traceutil/trace.go:171","msg":"trace[2087428186] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:697; }","duration":"216.762642ms","start":"2026-03-18T11:46:31.032129Z","end":"2026-03-18T11:46:31.248891Z","steps":["trace[2087428186] 'agreement among raft nodes before linearized reading' (duration: 216.670452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.249057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.813482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.249184Z","caller":"traceutil/trace.go:171","msg":"trace[1651994126] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:697; }","duration":"217.002055ms","start":"2026-03-18T11:46:31.032151Z","end":"2026-03-18T11:46:31.249153Z","steps":["trace[1651994126] 'agreement among raft nodes before linearized reading' (duration: 216.783193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.249404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.045457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.249451Z","caller":"traceutil/trace.go:171","msg":"trace[1220710894] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:697; }","duration":"190.101177ms","start":"2026-03-18T11:46:31.059333Z","end":"2026-03-18T11:46:31.249434Z","steps":["trace[1220710894] 'agreement among raft nodes before linearized reading' (duration: 190.032866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.249447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.183798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T11:46:31.249479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.247569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.249519Z","caller":"traceutil/trace.go:171","msg":"trace[403782792] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:697; }","duration":"190.314199ms","start":"2026-03-18T11:46:31.059191Z","end":"2026-03-18T11:46:31.249505Z","steps":["trace[403782792] 'agreement among raft nodes before linearized reading' (duration: 190.242519ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.249514Z","caller":"traceutil/trace.go:171","msg":"trace[142656456] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:697; }","duration":"190.273788ms","start":"2026-03-18T11:46:31.059223Z","end":"2026-03-18T11:46:31.249497Z","steps":["trace[142656456] 'agreement among raft nodes before linearized reading' (duration: 190.185258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.249599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.332907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.249626Z","caller":"traceutil/trace.go:171","msg":"trace[1970551872] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:697; }","duration":"217.406378ms","start":"2026-03-18T11:46:31.032209Z","end":"2026-03-18T11:46:31.249616Z","steps":["trace[1970551872] 'agreement among raft nodes before linearized reading' (duration: 217.370668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.250174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.502453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-18T11:46:31.25019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.038225ms","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-18T11:46:31.250228Z","caller":"traceutil/trace.go:171","msg":"trace[1950263642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"200.580863ms","start":"2026-03-18T11:46:31.049635Z","end":"2026-03-18T11:46:31.250216Z","steps":["trace[1950263642] 'agreement among raft nodes before linearized reading' (duration: 199.908158ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.250328Z","caller":"traceutil/trace.go:171","msg":"trace[31284164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"191.205826ms","start":"2026-03-18T11:46:31.059104Z","end":"2026-03-18T11:46:31.25031Z","steps":["trace[31284164] 'agreement among raft nodes before linearized reading' (duration: 190.280718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.250552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.675414ms","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-18T11:46:31.250582Z","caller":"traceutil/trace.go:171","msg":"trace[650229453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"200.719585ms","start":"2026-03-18T11:46:31.049855Z","end":"2026-03-18T11:46:31.250574Z","steps":["trace[650229453] 'agreement among raft nodes before linearized reading' (duration: 199.716056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.488601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.887673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:46:31.488772Z","caller":"traceutil/trace.go:171","msg":"trace[1772803467] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"233.118923ms","start":"2026-03-18T11:46:31.255625Z","end":"2026-03-18T11:46:31.488744Z","steps":["trace[1772803467] 'process raft request' (duration: 65.971387ms)","trace[1772803467] 'compare' (duration: 166.760582ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:31.519928Z","caller":"traceutil/trace.go:171","msg":"trace[1049304209] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"263.672533ms","start":"2026-03-18T11:46:31.256217Z","end":"2026-03-18T11:46:31.519889Z","steps":["trace[1049304209] 'process raft request' (duration: 263.429181ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.519938Z","caller":"traceutil/trace.go:171","msg":"trace[1024061551] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"262.835405ms","start":"2026-03-18T11:46:31.257075Z","end":"2026-03-18T11:46:31.51991Z","steps":["trace[1024061551] 'process raft request' (duration: 262.730425ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.520151Z","caller":"traceutil/trace.go:171","msg":"trace[1352174720] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"261.579754ms","start":"2026-03-18T11:46:31.258556Z","end":"2026-03-18T11:46:31.520136Z","steps":["trace[1352174720] 'process raft request' (duration: 261.289182ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.520132Z","caller":"traceutil/trace.go:171","msg":"trace[1092108215] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:727; }","duration":"110.348034ms","start":"2026-03-18T11:46:31.409774Z","end":"2026-03-18T11:46:31.520122Z","steps":["trace[1092108215] 'read index received' (duration: 109.85455ms)","trace[1092108215] 'applied index is now lower than readState.Index' (duration: 492.334µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:31.520283Z","caller":"traceutil/trace.go:171","msg":"trace[1239663472] transaction","detail":"{read_only:false; number_of_response:0; response_revision:701; }","duration":"261.654105ms","start":"2026-03-18T11:46:31.258583Z","end":"2026-03-18T11:46:31.520237Z","steps":["trace[1239663472] 'process raft request' (duration: 261.450684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:46:31.520363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.549516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T11:46:31.520408Z","caller":"traceutil/trace.go:171","msg":"trace[1739523593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"110.671778ms","start":"2026-03-18T11:46:31.409718Z","end":"2026-03-18T11:46:31.52039Z","steps":["trace[1739523593] 'agreement among raft nodes before linearized reading' (duration: 110.484776ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:31.959584Z","caller":"traceutil/trace.go:171","msg":"trace[714630833] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"217.377428ms","start":"2026-03-18T11:46:31.74216Z","end":"2026-03-18T11:46:31.959538Z","steps":["trace[714630833] 'process raft request' (duration: 217.164286ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:32.071615Z","caller":"traceutil/trace.go:171","msg":"trace[2120967942] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"107.288764ms","start":"2026-03-18T11:46:31.964298Z","end":"2026-03-18T11:46:32.071587Z","steps":["trace[2120967942] 'process raft request' (duration: 106.233714ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:32.435394Z","caller":"traceutil/trace.go:171","msg":"trace[881644268] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"170.971356ms","start":"2026-03-18T11:46:32.264397Z","end":"2026-03-18T11:46:32.435368Z","steps":["trace[881644268] 'read index received' (duration: 139.84006ms)","trace[881644268] 'applied index is now lower than readState.Index' (duration: 31.129896ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:46:32.43564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.235988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-gqbhb.189decfad1ee0aae\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-03-18T11:46:32.435671Z","caller":"traceutil/trace.go:171","msg":"trace[1849953938] range","detail":"{range_begin:/registry/events/kube-system/cilium-gqbhb.189decfad1ee0aae; range_end:; response_count:1; response_revision:705; }","duration":"171.34759ms","start":"2026-03-18T11:46:32.264316Z","end":"2026-03-18T11:46:32.435664Z","steps":["trace[1849953938] 'agreement among raft nodes before linearized reading' (duration: 171.187888ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:32.435479Z","caller":"traceutil/trace.go:171","msg":"trace[443995544] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"291.730634ms","start":"2026-03-18T11:46:32.143707Z","end":"2026-03-18T11:46:32.435437Z","steps":["trace[443995544] 'process raft request' (duration: 260.520237ms)","trace[443995544] 'compare' (duration: 30.841073ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:35.675953Z","caller":"traceutil/trace.go:171","msg":"trace[849833777] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"107.540226ms","start":"2026-03-18T11:46:35.568374Z","end":"2026-03-18T11:46:35.675914Z","steps":["trace[849833777] 'process raft request' (duration: 89.613113ms)","trace[849833777] 'compare' (duration: 17.776802ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:35.696284Z","caller":"traceutil/trace.go:171","msg":"trace[1015411941] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"127.144624ms","start":"2026-03-18T11:46:35.569121Z","end":"2026-03-18T11:46:35.696266Z","steps":["trace[1015411941] 'process raft request' (duration: 126.989673ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:35.696562Z","caller":"traceutil/trace.go:171","msg":"trace[1995079835] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"123.742384ms","start":"2026-03-18T11:46:35.572773Z","end":"2026-03-18T11:46:35.696515Z","steps":["trace[1995079835] 'process raft request' (duration: 123.447012ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:37.196506Z","caller":"traceutil/trace.go:171","msg":"trace[111420081] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"197.28921ms","start":"2026-03-18T11:46:36.998991Z","end":"2026-03-18T11:46:37.196281Z","steps":["trace[111420081] 'process raft request' (duration: 197.120059ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:37.20627Z","caller":"traceutil/trace.go:171","msg":"trace[99038927] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"145.853686ms","start":"2026-03-18T11:46:37.060404Z","end":"2026-03-18T11:46:37.206258Z","steps":["trace[99038927] 'process raft request' (duration: 145.768385ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:37.446391Z","caller":"traceutil/trace.go:171","msg":"trace[1305294847] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"225.97264ms","start":"2026-03-18T11:46:37.220396Z","end":"2026-03-18T11:46:37.446369Z","steps":["trace[1305294847] 'process raft request' (duration: 225.833868ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:37.629478Z","caller":"traceutil/trace.go:171","msg":"trace[586549830] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"176.780125ms","start":"2026-03-18T11:46:37.452642Z","end":"2026-03-18T11:46:37.629422Z","steps":["trace[586549830] 'process raft request' (duration: 156.568714ms)","trace[586549830] 'compare' (duration: 19.942759ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:46:37.629694Z","caller":"traceutil/trace.go:171","msg":"trace[2019878078] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"175.648345ms","start":"2026-03-18T11:46:37.454016Z","end":"2026-03-18T11:46:37.629665Z","steps":["trace[2019878078] 'process raft request' (duration: 175.310012ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:37.63026Z","caller":"traceutil/trace.go:171","msg":"trace[1426302066] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"171.502367ms","start":"2026-03-18T11:46:37.458743Z","end":"2026-03-18T11:46:37.630246Z","steps":["trace[1426302066] 'process raft request' (duration: 170.650519ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:46:44.372491Z","caller":"traceutil/trace.go:171","msg":"trace[1356219741] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"213.511839ms","start":"2026-03-18T11:46:44.158953Z","end":"2026-03-18T11:46:44.372465Z","steps":["trace[1356219741] 'process raft request' (duration: 213.359328ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:47:41.620837Z","caller":"traceutil/trace.go:171","msg":"trace[836701719] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"130.435708ms","start":"2026-03-18T11:47:41.490384Z","end":"2026-03-18T11:47:41.62082Z","steps":["trace[836701719] 'process raft request' (duration: 130.299977ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:47:49.868246Z","caller":"traceutil/trace.go:171","msg":"trace[981890712] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"194.506254ms","start":"2026-03-18T11:47:49.67371Z","end":"2026-03-18T11:47:49.868216Z","steps":["trace[981890712] 'process raft request' (duration: 194.317463ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:47:50.613762Z","caller":"traceutil/trace.go:171","msg":"trace[1574492668] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"364.414611ms","start":"2026-03-18T11:47:50.249321Z","end":"2026-03-18T11:47:50.613735Z","steps":["trace[1574492668] 'read index received' (duration: 364.31646ms)","trace[1574492668] 'applied index is now lower than readState.Index' (duration: 96.861µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:47:50.613872Z","caller":"traceutil/trace.go:171","msg":"trace[1765963935] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"408.927001ms","start":"2026-03-18T11:47:50.204918Z","end":"2026-03-18T11:47:50.613845Z","steps":["trace[1765963935] 'process raft request' (duration: 408.668139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:47:50.614001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.663983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T11:47:50.614037Z","caller":"traceutil/trace.go:171","msg":"trace[889102264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"364.731253ms","start":"2026-03-18T11:47:50.249293Z","end":"2026-03-18T11:47:50.614024Z","steps":["trace[889102264] 'agreement among raft nodes before linearized reading' (duration: 364.593732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:47:50.614067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:47:50.24928Z","time spent":"364.780793ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T11:47:50.614059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:47:50.204901Z","time spent":"409.048221ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:47:50.614125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.885918ms","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-18T11:47:50.614188Z","caller":"traceutil/trace.go:171","msg":"trace[771777000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"354.001678ms","start":"2026-03-18T11:47:50.260168Z","end":"2026-03-18T11:47:50.61417Z","steps":["trace[771777000] 'agreement among raft nodes before linearized reading' (duration: 353.836897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:47:50.614252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:47:50.260144Z","time spent":"354.10113ms","remote":"127.0.0.1:39790","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\" "}