{"level":"warn","ts":"2026-02-11T01:45:35.109278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:45:35.109403Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-02-11T01:45:35.109478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:45:35.109486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-11T01:45:35.109506Z","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-02-11T01:45:35.110171Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-02-11T01:45:35.11024Z","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":2,"max-cpu-available":2,"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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-02-11T01:45:35.114743Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.200316ms"} {"level":"info","ts":"2026-02-11T01:45:35.129561Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-02-11T01:45:35.129666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:45:35.129698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-02-11T01:45:35.129738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:45:35.129756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-02-11T01:45:35.129794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-02-11T01:45:35.135944Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:45:35.140474Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:45:35.145581Z","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-02-11T01:45:35.149643Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:45:35.150591Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:45:35.150763Z","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-02-11T01:45:35.150841Z","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-02-11T01:45:35.150891Z","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-02-11T01:45:35.178794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-02-11T01:45:35.178961Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-11T01:45:35.179777Z","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-02-11T01:45:35.179864Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-11T01:45:35.17994Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-11T01:45:35.180267Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:45:35.180372Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:45:35.631007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:45:35.631071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:45:35.631285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-02-11T01:45:35.631311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:45:35.631317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-11T01:45:35.631372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-02-11T01:45:35.631382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-11T01:45:35.635886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:45:35.636655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:45:35.636724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:45:35.639914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-02-11T01:45:35.640445Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:45:35.640563Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:45:35.640603Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:45:35.640855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:45:35.647535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:45:35.65331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:45:35.653338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:45:41.399947Z","caller":"traceutil/trace.go:171","msg":"trace[720964322] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"122.161873ms","start":"2026-02-11T01:45:41.277767Z","end":"2026-02-11T01:45:41.399929Z","steps":["trace[720964322] 'read index received' (duration: 90.791562ms)","trace[720964322] 'applied index is now lower than readState.Index' (duration: 31.369581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:41.40005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.261783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:41.40008Z","caller":"traceutil/trace.go:171","msg":"trace[1398465970] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:281; }","duration":"122.308994ms","start":"2026-02-11T01:45:41.277765Z","end":"2026-02-11T01:45:41.400074Z","steps":["trace[1398465970] 'agreement among raft nodes before linearized reading' (duration: 122.234603ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.400078Z","caller":"traceutil/trace.go:171","msg":"trace[898203830] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"127.282084ms","start":"2026-02-11T01:45:41.272772Z","end":"2026-02-11T01:45:41.400054Z","steps":["trace[898203830] 'process raft request' (duration: 95.776752ms)","trace[898203830] 'compare' (duration: 31.29083ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:41.400555Z","caller":"traceutil/trace.go:171","msg":"trace[2142006600] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"107.372665ms","start":"2026-02-11T01:45:41.293176Z","end":"2026-02-11T01:45:41.400549Z","steps":["trace[2142006600] 'process raft request' (duration: 107.186894ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.400615Z","caller":"traceutil/trace.go:171","msg":"trace[1879867545] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"103.540464ms","start":"2026-02-11T01:45:41.29707Z","end":"2026-02-11T01:45:41.40061Z","steps":["trace[1879867545] 'process raft request' (duration: 103.324052ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.40066Z","caller":"traceutil/trace.go:171","msg":"trace[1200945247] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"103.487744ms","start":"2026-02-11T01:45:41.297169Z","end":"2026-02-11T01:45:41.400657Z","steps":["trace[1200945247] 'process raft request' (duration: 103.242612ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.400741Z","caller":"traceutil/trace.go:171","msg":"trace[1216624334] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"102.592157ms","start":"2026-02-11T01:45:41.298145Z","end":"2026-02-11T01:45:41.400737Z","steps":["trace[1216624334] 'process raft request' (duration: 102.275794ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.400799Z","caller":"traceutil/trace.go:171","msg":"trace[1993550689] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"100.003256ms","start":"2026-02-11T01:45:41.300791Z","end":"2026-02-11T01:45:41.400794Z","steps":["trace[1993550689] 'process raft request' (duration: 99.651683ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:41.40088Z","caller":"traceutil/trace.go:171","msg":"trace[1107560698] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"100.063757ms","start":"2026-02-11T01:45:41.300812Z","end":"2026-02-11T01:45:41.400876Z","steps":["trace[1107560698] 'process raft request' (duration: 99.645314ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:45.11725Z","caller":"traceutil/trace.go:171","msg":"trace[1446623126] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"154.418336ms","start":"2026-02-11T01:45:44.962812Z","end":"2026-02-11T01:45:45.11723Z","steps":["trace[1446623126] 'read index received' (duration: 154.267174ms)","trace[1446623126] 'applied index is now lower than readState.Index' (duration: 150.322µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:45.117414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.581448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:45.117449Z","caller":"traceutil/trace.go:171","msg":"trace[2074314018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:335; }","duration":"154.656208ms","start":"2026-02-11T01:45:44.962782Z","end":"2026-02-11T01:45:45.117439Z","steps":["trace[2074314018] 'agreement among raft nodes before linearized reading' (duration: 154.562637ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:45.117869Z","caller":"traceutil/trace.go:171","msg":"trace[960153970] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"184.537318ms","start":"2026-02-11T01:45:44.933322Z","end":"2026-02-11T01:45:45.117859Z","steps":["trace[960153970] 'process raft request' (duration: 183.810052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.63494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.664748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:45.635199Z","caller":"traceutil/trace.go:171","msg":"trace[1481676854] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"409.405092ms","start":"2026-02-11T01:45:45.22575Z","end":"2026-02-11T01:45:45.635155Z","steps":["trace[1481676854] 'read index received' (duration: 246.460095ms)","trace[1481676854] 'applied index is now lower than readState.Index' (duration: 162.943627ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:45.635448Z","caller":"traceutil/trace.go:171","msg":"trace[297353067] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"508.021798ms","start":"2026-02-11T01:45:45.127398Z","end":"2026-02-11T01:45:45.635419Z","steps":["trace[297353067] 'process raft request' (duration: 344.864828ms)","trace[297353067] 'compare' (duration: 161.524377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:45.638784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.794085ms","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-02-11T01:45:45.638844Z","caller":"traceutil/trace.go:171","msg":"trace[578991047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:337; }","duration":"354.875525ms","start":"2026-02-11T01:45:45.283952Z","end":"2026-02-11T01:45:45.638827Z","steps":["trace[578991047] 'agreement among raft nodes before linearized reading' (duration: 354.667964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.639145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.28393Z","time spent":"355.129847ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:45:45.638967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.127381Z","time spent":"511.341283ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:45.635595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.821786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:45.640934Z","caller":"traceutil/trace.go:171","msg":"trace[1003471028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:337; }","duration":"415.170736ms","start":"2026-02-11T01:45:45.225745Z","end":"2026-02-11T01:45:45.640916Z","steps":["trace[1003471028] 'agreement among raft nodes before linearized reading' (duration: 409.791715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.641059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.225714Z","time spent":"415.329328ms","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-11T01:45:46.159723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.456775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:46.160181Z","caller":"traceutil/trace.go:171","msg":"trace[581502445] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"512.869244ms","start":"2026-02-11T01:45:45.647288Z","end":"2026-02-11T01:45:46.160157Z","steps":["trace[581502445] 'process raft request' (duration: 103.908055ms)","trace[581502445] 'compare' (duration: 408.335714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:46.160263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.647273Z","time spent":"512.952924ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:46.162009Z","caller":"traceutil/trace.go:171","msg":"trace[139575059] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"511.734474ms","start":"2026-02-11T01:45:45.650259Z","end":"2026-02-11T01:45:46.161993Z","steps":["trace[139575059] 'process raft request' (duration: 511.16259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:46.162609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.65025Z","time spent":"512.300339ms","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:45:46.163005Z","caller":"traceutil/trace.go:171","msg":"trace[140894654] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:346; }","duration":"450.195384ms","start":"2026-02-11T01:45:45.712781Z","end":"2026-02-11T01:45:46.162976Z","steps":["trace[140894654] 'read index received' (duration: 38.426494ms)","trace[140894654] 'applied index is now lower than readState.Index' (duration: 411.76792ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:46.163197Z","caller":"traceutil/trace.go:171","msg":"trace[90429296] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"506.532105ms","start":"2026-02-11T01:45:45.656657Z","end":"2026-02-11T01:45:46.16319Z","steps":["trace[90429296] 'process raft request' (duration: 504.869662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:46.163275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.656651Z","time spent":"506.572086ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:46.163411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.629497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:46.163436Z","caller":"traceutil/trace.go:171","msg":"trace[524806057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:340; }","duration":"450.675397ms","start":"2026-02-11T01:45:45.712755Z","end":"2026-02-11T01:45:46.16343Z","steps":["trace[524806057] 'agreement among raft nodes before linearized reading' (duration: 450.633657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:46.163456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.712729Z","time spent":"450.720927ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:45:46.163557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.153874ms","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-02-11T01:45:46.163578Z","caller":"traceutil/trace.go:171","msg":"trace[681946698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:340; }","duration":"288.240694ms","start":"2026-02-11T01:45:45.875332Z","end":"2026-02-11T01:45:46.163573Z","steps":["trace[681946698] 'agreement among raft nodes before linearized reading' (duration: 288.205954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:46.16375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.800044ms","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-02-11T01:45:46.163773Z","caller":"traceutil/trace.go:171","msg":"trace[865863928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:340; }","duration":"121.823104ms","start":"2026-02-11T01:45:46.041944Z","end":"2026-02-11T01:45:46.163768Z","steps":["trace[865863928] 'agreement among raft nodes before linearized reading' (duration: 121.755833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:46.16385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.224404ms","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-02-11T01:45:46.16387Z","caller":"traceutil/trace.go:171","msg":"trace[1921016741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:340; }","duration":"275.258624ms","start":"2026-02-11T01:45:45.888606Z","end":"2026-02-11T01:45:46.163865Z","steps":["trace[1921016741] 'agreement among raft nodes before linearized reading' (duration: 275.227334ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:51.577249Z","caller":"traceutil/trace.go:171","msg":"trace[569106508] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"367.403276ms","start":"2026-02-11T01:45:51.209822Z","end":"2026-02-11T01:45:51.577225Z","steps":["trace[569106508] 'process raft request' (duration: 367.250085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.577423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:51.209804Z","time spent":"367.539157ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:52.084878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870685636893835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:52.868979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.517183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:52.870957Z","caller":"traceutil/trace.go:171","msg":"trace[161347757] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"1.28659707s","start":"2026-02-11T01:45:51.584339Z","end":"2026-02-11T01:45:52.870936Z","steps":["trace[161347757] 'read index received' (duration: 659.938293ms)","trace[161347757] 'applied index is now lower than readState.Index' (duration: 626.656577ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:52.871207Z","caller":"traceutil/trace.go:171","msg":"trace[1811588011] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.495073553s","start":"2026-02-11T01:45:51.376119Z","end":"2026-02-11T01:45:52.871193Z","steps":["trace[1811588011] 'process raft request' (duration: 868.277505ms)","trace[1811588011] 'compare' (duration: 623.895887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:52.871279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:51.376096Z","time spent":"1.495145423s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:52.871484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287140695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7177"} {"level":"info","ts":"2026-02-11T01:45:52.871522Z","caller":"traceutil/trace.go:171","msg":"trace[452390219] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:414; }","duration":"1.287201636s","start":"2026-02-11T01:45:51.58431Z","end":"2026-02-11T01:45:52.871512Z","steps":["trace[452390219] 'agreement among raft nodes before linearized reading' (duration: 1.287135746s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.871551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:51.584296Z","time spent":"1.287248896s","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7200,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-11T01:45:52.87169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156006744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:52.871721Z","caller":"traceutil/trace.go:171","msg":"trace[114559197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"1.156035565s","start":"2026-02-11T01:45:51.715678Z","end":"2026-02-11T01:45:52.871713Z","steps":["trace[114559197] 'agreement among raft nodes before linearized reading' (duration: 1.155993234s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.871744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:51.715633Z","time spent":"1.156106435s","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:45:52.87194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.321652ms","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-02-11T01:45:52.871975Z","caller":"traceutil/trace.go:171","msg":"trace[1177908799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"563.370452ms","start":"2026-02-11T01:45:52.308596Z","end":"2026-02-11T01:45:52.871967Z","steps":["trace[1177908799] 'agreement among raft nodes before linearized reading' (duration: 563.316702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.872003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:52.308591Z","time spent":"563.404153ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:52.872144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.565423ms","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-02-11T01:45:52.872176Z","caller":"traceutil/trace.go:171","msg":"trace[284803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"563.630955ms","start":"2026-02-11T01:45:52.308537Z","end":"2026-02-11T01:45:52.872168Z","steps":["trace[284803] 'agreement among raft nodes before linearized reading' (duration: 563.584004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.872206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:52.308518Z","time spent":"563.676825ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:52.872324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.309645ms","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-02-11T01:45:52.872361Z","caller":"traceutil/trace.go:171","msg":"trace[1150227080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"605.364935ms","start":"2026-02-11T01:45:52.266982Z","end":"2026-02-11T01:45:52.872346Z","steps":["trace[1150227080] 'agreement among raft nodes before linearized reading' (duration: 605.318245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.872387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:52.266966Z","time spent":"605.414795ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:45:52.872527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.262396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T01:45:52.87256Z","caller":"traceutil/trace.go:171","msg":"trace[78238284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"661.329797ms","start":"2026-02-11T01:45:52.211222Z","end":"2026-02-11T01:45:52.872552Z","steps":["trace[78238284] 'agreement among raft nodes before linearized reading' (duration: 661.280757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:52.872584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:52.211205Z","time spent":"661.373737ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T01:45:53.033454Z","caller":"traceutil/trace.go:171","msg":"trace[1431512089] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"150.32946ms","start":"2026-02-11T01:45:52.883101Z","end":"2026-02-11T01:45:53.03343Z","steps":["trace[1431512089] 'process raft request' (duration: 150.105238ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:53.033651Z","caller":"traceutil/trace.go:171","msg":"trace[416815068] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"153.974395ms","start":"2026-02-11T01:45:52.87966Z","end":"2026-02-11T01:45:53.033634Z","steps":["trace[416815068] 'process raft request' (duration: 91.424897ms)","trace[416815068] 'compare' (duration: 62.005774ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:53.034063Z","caller":"traceutil/trace.go:171","msg":"trace[757931223] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"150.790503ms","start":"2026-02-11T01:45:52.883179Z","end":"2026-02-11T01:45:53.03397Z","steps":["trace[757931223] 'process raft request' (duration: 150.089568ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:53.034794Z","caller":"traceutil/trace.go:171","msg":"trace[897714920] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"148.053023ms","start":"2026-02-11T01:45:52.886648Z","end":"2026-02-11T01:45:53.034701Z","steps":["trace[897714920] 'process raft request' (duration: 147.270028ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:53.037366Z","caller":"traceutil/trace.go:171","msg":"trace[4468675] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:425; }","duration":"151.611049ms","start":"2026-02-11T01:45:52.885742Z","end":"2026-02-11T01:45:53.037353Z","steps":["trace[4468675] 'read index received' (duration: 85.351544ms)","trace[4468675] 'applied index is now lower than readState.Index' (duration: 66.258555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:53.0376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.803431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:53.037689Z","caller":"traceutil/trace.go:171","msg":"trace[1785595557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"151.916201ms","start":"2026-02-11T01:45:52.88572Z","end":"2026-02-11T01:45:53.037636Z","steps":["trace[1785595557] 'agreement among raft nodes before linearized reading' (duration: 151.72528ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:53.038133Z","caller":"traceutil/trace.go:171","msg":"trace[530132212] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"151.465418ms","start":"2026-02-11T01:45:52.886656Z","end":"2026-02-11T01:45:53.038122Z","steps":["trace[530132212] 'process raft request' (duration: 147.691091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.216123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870685636894039,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:46:09.355129Z","caller":"traceutil/trace.go:171","msg":"trace[1499808864] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"639.308238ms","start":"2026-02-11T01:46:08.715804Z","end":"2026-02-11T01:46:09.355112Z","steps":["trace[1499808864] 'read index received' (duration: 639.155907ms)","trace[1499808864] 'applied index is now lower than readState.Index' (duration: 151.751µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:09.355339Z","caller":"traceutil/trace.go:171","msg":"trace[719884831] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"682.498029ms","start":"2026-02-11T01:46:08.672832Z","end":"2026-02-11T01:46:09.35533Z","steps":["trace[719884831] 'process raft request' (duration: 682.185767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.355424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:08.672813Z","time spent":"682.549999ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:09.355565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.763521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:09.355588Z","caller":"traceutil/trace.go:171","msg":"trace[2109950630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"639.807481ms","start":"2026-02-11T01:46:08.715773Z","end":"2026-02-11T01:46:09.35558Z","steps":["trace[2109950630] 'agreement among raft nodes before linearized reading' (duration: 639.768961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.355629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:08.715759Z","time spent":"639.843851ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:46:09.355732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.82598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T01:46:09.355751Z","caller":"traceutil/trace.go:171","msg":"trace[567670987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"234.8787ms","start":"2026-02-11T01:46:09.120866Z","end":"2026-02-11T01:46:09.355745Z","steps":["trace[567670987] 'agreement among raft nodes before linearized reading' (duration: 234.84553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.355855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.979145ms","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-02-11T01:46:09.355873Z","caller":"traceutil/trace.go:171","msg":"trace[1977832746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"175.013795ms","start":"2026-02-11T01:46:09.180854Z","end":"2026-02-11T01:46:09.355867Z","steps":["trace[1977832746] 'agreement among raft nodes before linearized reading' (duration: 174.982245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.35595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.262168ms","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-02-11T01:46:09.35597Z","caller":"traceutil/trace.go:171","msg":"trace[1495176049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"194.303838ms","start":"2026-02-11T01:46:09.161659Z","end":"2026-02-11T01:46:09.355963Z","steps":["trace[1495176049] 'agreement among raft nodes before linearized reading' (duration: 194.272288ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:16.772609Z","caller":"traceutil/trace.go:171","msg":"trace[621528454] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"431.935175ms","start":"2026-02-11T01:46:16.340657Z","end":"2026-02-11T01:46:16.772592Z","steps":["trace[621528454] 'process raft request' (duration: 431.824324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:16.772704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:16.340645Z","time spent":"432.011345ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:18.025468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870685636894133,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:46:18.091519Z","caller":"traceutil/trace.go:171","msg":"trace[2071923434] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"566.553013ms","start":"2026-02-11T01:46:17.524945Z","end":"2026-02-11T01:46:18.091498Z","steps":["trace[2071923434] 'read index received' (duration: 566.447702ms)","trace[2071923434] 'applied index is now lower than readState.Index' (duration: 104.591µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:18.092136Z","caller":"traceutil/trace.go:171","msg":"trace[1534165078] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"614.603372ms","start":"2026-02-11T01:46:17.477522Z","end":"2026-02-11T01:46:18.092125Z","steps":["trace[1534165078] 'process raft request' (duration: 613.862286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:18.092218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:17.477511Z","time spent":"614.654292ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:18.092252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.371383ms","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-02-11T01:46:18.092303Z","caller":"traceutil/trace.go:171","msg":"trace[1108999929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"558.461303ms","start":"2026-02-11T01:46:17.533828Z","end":"2026-02-11T01:46:18.092289Z","steps":["trace[1108999929] 'agreement among raft nodes before linearized reading' (duration: 558.348183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:18.092329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:17.533811Z","time spent":"558.512515ms","remote":"127.0.0.1:47102","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-02-11T01:46:18.092462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.521349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:46:18.09248Z","caller":"traceutil/trace.go:171","msg":"trace[932550839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"567.558661ms","start":"2026-02-11T01:46:17.524913Z","end":"2026-02-11T01:46:18.092472Z","steps":["trace[932550839] 'agreement among raft nodes before linearized reading' (duration: 567.49098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:18.092494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:17.5249Z","time spent":"567.591341ms","remote":"127.0.0.1:47102","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-02-11T01:46:18.092534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.763596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:18.092544Z","caller":"traceutil/trace.go:171","msg":"trace[1759700478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"377.807516ms","start":"2026-02-11T01:46:17.714733Z","end":"2026-02-11T01:46:18.09254Z","steps":["trace[1759700478] 'agreement among raft nodes before linearized reading' (duration: 377.791626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:18.092554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:17.714714Z","time spent":"377.838266ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:46:18.092706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.814656ms","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-02-11T01:46:18.09272Z","caller":"traceutil/trace.go:171","msg":"trace[6937199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"558.828096ms","start":"2026-02-11T01:46:17.533888Z","end":"2026-02-11T01:46:18.092716Z","steps":["trace[6937199] 'agreement among raft nodes before linearized reading' (duration: 558.783846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:18.092731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:17.533872Z","time spent":"558.856616ms","remote":"127.0.0.1:47102","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-02-11T01:46:19.824034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.780283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:46:19.824103Z","caller":"traceutil/trace.go:171","msg":"trace[1718509402] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:566; }","duration":"487.888554ms","start":"2026-02-11T01:46:19.336197Z","end":"2026-02-11T01:46:19.824085Z","steps":["trace[1718509402] 'range keys from in-memory index tree' (duration: 487.637742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:19.824141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:19.336179Z","time spent":"487.953914ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-02-11T01:46:20.215822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870685636894141,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:46:20.229296Z","caller":"traceutil/trace.go:171","msg":"trace[1256435840] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"681.007364ms","start":"2026-02-11T01:46:19.548274Z","end":"2026-02-11T01:46:20.229282Z","steps":["trace[1256435840] 'process raft request' (duration: 680.928074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:20.229394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:19.54825Z","time spent":"681.091985ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:21.007789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.7827ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-11T01:46:21.007887Z","caller":"traceutil/trace.go:171","msg":"trace[1576435135] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"1.2929162s","start":"2026-02-11T01:46:19.714954Z","end":"2026-02-11T01:46:21.00787Z","steps":["trace[1576435135] 'read index received' (duration: 514.673353ms)","trace[1576435135] 'applied index is now lower than readState.Index' (duration: 778.241207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:21.007966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:19.825357Z","time spent":"1.182606974s","remote":"127.0.0.1:46872","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:46:21.008195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293237862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:21.008236Z","caller":"traceutil/trace.go:171","msg":"trace[226521371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.293338332s","start":"2026-02-11T01:46:19.714886Z","end":"2026-02-11T01:46:21.008225Z","steps":["trace[226521371] 'agreement among raft nodes before linearized reading' (duration: 1.293261662s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.008266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:19.714839Z","time spent":"1.293419903s","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:46:21.00849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.639366ms","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-02-11T01:46:21.008521Z","caller":"traceutil/trace.go:171","msg":"trace[764783927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"910.691996ms","start":"2026-02-11T01:46:20.097819Z","end":"2026-02-11T01:46:21.008511Z","steps":["trace[764783927] 'agreement among raft nodes before linearized reading' (duration: 910.580155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.008548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:20.097805Z","time spent":"910.736896ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:46:21.008754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.181892ms","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-02-11T01:46:21.008783Z","caller":"traceutil/trace.go:171","msg":"trace[234968278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"869.211962ms","start":"2026-02-11T01:46:20.139563Z","end":"2026-02-11T01:46:21.008775Z","steps":["trace[234968278] 'agreement among raft nodes before linearized reading' (duration: 869.157312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.008813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:20.139542Z","time spent":"869.265463ms","remote":"127.0.0.1:47102","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-02-11T01:46:21.00892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.663726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:46:21.008983Z","caller":"traceutil/trace.go:171","msg":"trace[1110144405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"874.781836ms","start":"2026-02-11T01:46:20.134186Z","end":"2026-02-11T01:46:21.008968Z","steps":["trace[1110144405] 'agreement among raft nodes before linearized reading' (duration: 874.662906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.009026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:20.134162Z","time spent":"874.855937ms","remote":"127.0.0.1:47102","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-02-11T01:46:21.009139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.190384ms","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-02-11T01:46:21.009176Z","caller":"traceutil/trace.go:171","msg":"trace[2146012549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"871.270526ms","start":"2026-02-11T01:46:20.137893Z","end":"2026-02-11T01:46:21.009163Z","steps":["trace[2146012549] 'agreement among raft nodes before linearized reading' (duration: 871.214895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.00921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:20.137862Z","time spent":"871.339236ms","remote":"127.0.0.1:47102","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-02-11T01:46:21.523644Z","caller":"traceutil/trace.go:171","msg":"trace[905499696] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"512.400131ms","start":"2026-02-11T01:46:21.011214Z","end":"2026-02-11T01:46:21.523614Z","steps":["trace[905499696] 'process raft request' (duration: 452.770137ms)","trace[905499696] 'compare' (duration: 59.18348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:21.523778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.011205Z","time spent":"512.504661ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:21.596973Z","caller":"traceutil/trace.go:171","msg":"trace[619640096] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:585; }","duration":"573.958635ms","start":"2026-02-11T01:46:21.023Z","end":"2026-02-11T01:46:21.596958Z","steps":["trace[619640096] 'read index received' (duration: 440.997545ms)","trace[619640096] 'applied index is now lower than readState.Index' (duration: 132.96056ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:21.59713Z","caller":"traceutil/trace.go:171","msg":"trace[8624504] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"582.073495ms","start":"2026-02-11T01:46:21.015043Z","end":"2026-02-11T01:46:21.597117Z","steps":["trace[8624504] 'process raft request' (duration: 581.779883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.5972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.015022Z","time spent":"582.129285ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:21.597297Z","caller":"traceutil/trace.go:171","msg":"trace[1265963690] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"575.957137ms","start":"2026-02-11T01:46:21.021336Z","end":"2026-02-11T01:46:21.597293Z","steps":["trace[1265963690] 'process raft request' (duration: 575.565845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.597324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.021326Z","time spent":"575.985258ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:21.597376Z","caller":"traceutil/trace.go:171","msg":"trace[476718926] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"575.559054ms","start":"2026-02-11T01:46:21.021813Z","end":"2026-02-11T01:46:21.597372Z","steps":["trace[476718926] 'process raft request' (duration: 575.109362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.597412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.021807Z","time spent":"575.591075ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:21.597462Z","caller":"traceutil/trace.go:171","msg":"trace[683065873] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"575.610454ms","start":"2026-02-11T01:46:21.021847Z","end":"2026-02-11T01:46:21.597457Z","steps":["trace[683065873] 'process raft request' (duration: 575.093301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.597488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.021844Z","time spent":"575.632785ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:21.597547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.546468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:21.597568Z","caller":"traceutil/trace.go:171","msg":"trace[1660828376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"574.583229ms","start":"2026-02-11T01:46:21.02298Z","end":"2026-02-11T01:46:21.597563Z","steps":["trace[1660828376] 'agreement among raft nodes before linearized reading' (duration: 574.553738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:21.597581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:21.022974Z","time spent":"574.604979ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:46:22.508681Z","caller":"traceutil/trace.go:171","msg":"trace[1040991214] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"149.990275ms","start":"2026-02-11T01:46:22.358671Z","end":"2026-02-11T01:46:22.508662Z","steps":["trace[1040991214] 'process raft request' (duration: 149.876014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.304609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.512371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T01:46:24.304676Z","caller":"traceutil/trace.go:171","msg":"trace[1993210267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"702.617396ms","start":"2026-02-11T01:46:23.602043Z","end":"2026-02-11T01:46:24.304661Z","steps":["trace[1993210267] 'range keys from in-memory index tree' (duration: 702.387976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.304717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.602028Z","time spent":"702.679269ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:46:24.304965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.69421ms","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-02-11T01:46:24.30502Z","caller":"traceutil/trace.go:171","msg":"trace[1484251995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"702.758143ms","start":"2026-02-11T01:46:23.602252Z","end":"2026-02-11T01:46:24.30501Z","steps":["trace[1484251995] 'range keys from in-memory index tree' (duration: 702.628177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.305045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.602247Z","time spent":"702.792304ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:46:24.3052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.847978ms","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-02-11T01:46:24.305226Z","caller":"traceutil/trace.go:171","msg":"trace[238161306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"700.88172ms","start":"2026-02-11T01:46:23.604336Z","end":"2026-02-11T01:46:24.305218Z","steps":["trace[238161306] 'range keys from in-memory index tree' (duration: 700.781875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.305249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.604331Z","time spent":"700.912961ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:46:24.305324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.529643ms","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-02-11T01:46:24.305344Z","caller":"traceutil/trace.go:171","msg":"trace[1986762595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"700.558564ms","start":"2026-02-11T01:46:23.604779Z","end":"2026-02-11T01:46:24.305338Z","steps":["trace[1986762595] 'range keys from in-memory index tree' (duration: 700.483851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.30536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.604775Z","time spent":"700.581875ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:46:24.305437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.346606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:24.305449Z","caller":"traceutil/trace.go:171","msg":"trace[639641735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"589.388337ms","start":"2026-02-11T01:46:23.716056Z","end":"2026-02-11T01:46:24.305445Z","steps":["trace[639641735] 'range keys from in-memory index tree' (duration: 589.282202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.30546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.716039Z","time spent":"589.418679ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:46:24.305674Z","caller":"traceutil/trace.go:171","msg":"trace[1624453512] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"477.868343ms","start":"2026-02-11T01:46:23.8278Z","end":"2026-02-11T01:46:24.305669Z","steps":["trace[1624453512] 'process raft request' (duration: 474.994686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:24.305719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:23.827778Z","time spent":"477.909985ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:44.673602Z","caller":"traceutil/trace.go:171","msg":"trace[2132384175] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"169.199445ms","start":"2026-02-11T01:46:44.504383Z","end":"2026-02-11T01:46:44.673583Z","steps":["trace[2132384175] 'process raft request' (duration: 169.04426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:45.382582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870685636894574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:46:45.7306Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057271936s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:46:45.731792Z","caller":"traceutil/trace.go:171","msg":"trace[790607279] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.101466074s","start":"2026-02-11T01:46:44.630304Z","end":"2026-02-11T01:46:45.73177Z","steps":["trace[790607279] 'process raft request' (duration: 1.101425593s)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:45.732895Z","caller":"traceutil/trace.go:171","msg":"trace[845743603] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.226258352s","start":"2026-02-11T01:46:44.50662Z","end":"2026-02-11T01:46:45.732878Z","steps":["trace[845743603] 'process raft request' (duration: 1.224974178s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:45.735286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:44.630287Z","time spent":"1.10480591s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:45.735336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:44.506602Z","time spent":"1.228610114s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:45.73319Z","caller":"traceutil/trace.go:171","msg":"trace[298107472] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.226527772s","start":"2026-02-11T01:46:44.506651Z","end":"2026-02-11T01:46:45.733179Z","steps":["trace[298107472] 'process raft request' (duration: 1.225042911s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:45.735598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:44.506648Z","time spent":"1.228929984s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:45.884829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.545533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T01:46:45.884925Z","caller":"traceutil/trace.go:171","msg":"trace[1288738418] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"1.002484366s","start":"2026-02-11T01:46:44.882424Z","end":"2026-02-11T01:46:45.884908Z","steps":["trace[1288738418] 'read index received' (duration: 850.438717ms)","trace[1288738418] 'applied index is now lower than readState.Index' (duration: 152.044279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:45.885296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002864019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:46:45.885331Z","caller":"traceutil/trace.go:171","msg":"trace[212922524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.002936752s","start":"2026-02-11T01:46:44.882382Z","end":"2026-02-11T01:46:45.885319Z","steps":["trace[212922524] 'agreement among raft nodes before linearized reading' (duration: 1.002771926s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:45.885362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:44.882367Z","time spent":"1.002987613s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:46:45.885554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.769304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:45.885582Z","caller":"traceutil/trace.go:171","msg":"trace[1473383896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"170.826975ms","start":"2026-02-11T01:46:45.714744Z","end":"2026-02-11T01:46:45.885571Z","steps":["trace[1473383896] 'agreement among raft nodes before linearized reading' (duration: 170.788874ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:48.133466Z","caller":"traceutil/trace.go:171","msg":"trace[317383415] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"186.654859ms","start":"2026-02-11T01:46:47.946789Z","end":"2026-02-11T01:46:48.133444Z","steps":["trace[317383415] 'read index received' (duration: 159.562007ms)","trace[317383415] 'applied index is now lower than readState.Index' (duration: 27.091942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:48.133632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.828674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:46:48.133665Z","caller":"traceutil/trace.go:171","msg":"trace[723945148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"186.892267ms","start":"2026-02-11T01:46:47.946764Z","end":"2026-02-11T01:46:48.133656Z","steps":["trace[723945148] 'agreement among raft nodes before linearized reading' (duration: 186.749632ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:48.133878Z","caller":"traceutil/trace.go:171","msg":"trace[323284298] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"389.349697ms","start":"2026-02-11T01:46:47.744519Z","end":"2026-02-11T01:46:48.133869Z","steps":["trace[323284298] 'process raft request' (duration: 361.84089ms)","trace[323284298] 'compare' (duration: 27.001639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:48.133947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:47.744493Z","time spent":"389.409189ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:50.313523Z","caller":"traceutil/trace.go:171","msg":"trace[2005078965] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"137.308824ms","start":"2026-02-11T01:46:50.176199Z","end":"2026-02-11T01:46:50.313508Z","steps":["trace[2005078965] 'process raft request' (duration: 137.221001ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:52.314149Z","caller":"traceutil/trace.go:171","msg":"trace[850750591] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"143.789954ms","start":"2026-02-11T01:46:52.170331Z","end":"2026-02-11T01:46:52.314121Z","steps":["trace[850750591] 'process raft request' (duration: 143.69136ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:18.145097Z","caller":"traceutil/trace.go:171","msg":"trace[985719443] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"147.807942ms","start":"2026-02-11T01:47:17.99727Z","end":"2026-02-11T01:47:18.145078Z","steps":["trace[985719443] 'process raft request' (duration: 147.70416ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:19.468384Z","caller":"traceutil/trace.go:171","msg":"trace[925091951] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"103.191858ms","start":"2026-02-11T01:47:19.365163Z","end":"2026-02-11T01:47:19.468355Z","steps":["trace[925091951] 'process raft request' (duration: 50.299048ms)","trace[925091951] 'compare' (duration: 52.805278ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:20.309149Z","caller":"traceutil/trace.go:171","msg":"trace[542242459] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"158.959202ms","start":"2026-02-11T01:47:20.150168Z","end":"2026-02-11T01:47:20.309127Z","steps":["trace[542242459] 'process raft request' (duration: 158.826999ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:20.64375Z","caller":"traceutil/trace.go:171","msg":"trace[1758303868] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"111.318618ms","start":"2026-02-11T01:47:20.532412Z","end":"2026-02-11T01:47:20.643731Z","steps":["trace[1758303868] 'process raft request' (duration: 52.083178ms)","trace[1758303868] 'compare' (duration: 58.935833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:49.167484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.225413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:47:49.167584Z","caller":"traceutil/trace.go:171","msg":"trace[158697327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"197.360166ms","start":"2026-02-11T01:47:48.970203Z","end":"2026-02-11T01:47:49.167563Z","steps":["trace[158697327] 'range keys from in-memory index tree' (duration: 197.110301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:49.167995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.486086ms","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-02-11T01:47:49.168053Z","caller":"traceutil/trace.go:171","msg":"trace[1516577805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"317.580028ms","start":"2026-02-11T01:47:48.850459Z","end":"2026-02-11T01:47:49.168039Z","steps":["trace[1516577805] 'range keys from in-memory index tree' (duration: 317.375254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:49.168526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:48.85044Z","time spent":"318.029886ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:47:49.652139Z","caller":"traceutil/trace.go:171","msg":"trace[1693391242] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"312.720574ms","start":"2026-02-11T01:47:49.339392Z","end":"2026-02-11T01:47:49.652112Z","steps":["trace[1693391242] 'read index received' (duration: 256.138853ms)","trace[1693391242] 'applied index is now lower than readState.Index' (duration: 56.580411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:49.652515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.099651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:47:49.652619Z","caller":"traceutil/trace.go:171","msg":"trace[1097498001] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1005; }","duration":"313.212113ms","start":"2026-02-11T01:47:49.339352Z","end":"2026-02-11T01:47:49.652565Z","steps":["trace[1097498001] 'agreement among raft nodes before linearized reading' (duration: 312.862827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:49.65274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:49.339322Z","time spent":"313.401997ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-02-11T01:47:49.65374Z","caller":"traceutil/trace.go:171","msg":"trace[1016669995] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"402.65975ms","start":"2026-02-11T01:47:49.251063Z","end":"2026-02-11T01:47:49.653723Z","steps":["trace[1016669995] 'process raft request' (duration: 344.52123ms)","trace[1016669995] 'compare' (duration: 54.792018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:49.65453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:49.25105Z","time spent":"402.732302ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:47:49.912619Z","caller":"traceutil/trace.go:171","msg":"trace[1645463150] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"196.538545ms","start":"2026-02-11T01:47:49.716064Z","end":"2026-02-11T01:47:49.912602Z","steps":["trace[1645463150] 'read index received' (duration: 148.75514ms)","trace[1645463150] 'applied index is now lower than readState.Index' (duration: 47.782365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:49.912701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.624648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:47:49.912729Z","caller":"traceutil/trace.go:171","msg":"trace[1584509170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"196.686989ms","start":"2026-02-11T01:47:49.716025Z","end":"2026-02-11T01:47:49.912712Z","steps":["trace[1584509170] 'agreement among raft nodes before linearized reading' (duration: 196.639718ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:13.544114Z","caller":"traceutil/trace.go:171","msg":"trace[845509938] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"142.215054ms","start":"2026-02-11T01:48:13.401879Z","end":"2026-02-11T01:48:13.544094Z","steps":["trace[845509938] 'read index received' (duration: 10.631699ms)","trace[845509938] 'applied index is now lower than readState.Index' (duration: 131.582275ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:48:13.544483Z","caller":"traceutil/trace.go:171","msg":"trace[834702435] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"142.848953ms","start":"2026-02-11T01:48:13.401604Z","end":"2026-02-11T01:48:13.544453Z","steps":["trace[834702435] 'process raft request' (duration: 97.637463ms)","trace[834702435] 'compare' (duration: 44.754083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:13.54472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.65814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-ffwln\" ","response":"range_response_count:1 size:4415"} {"level":"info","ts":"2026-02-11T01:48:13.544965Z","caller":"traceutil/trace.go:171","msg":"trace[1037920028] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-ffwln; range_end:; response_count:1; response_revision:1076; }","duration":"143.081798ms","start":"2026-02-11T01:48:13.401867Z","end":"2026-02-11T01:48:13.544949Z","steps":["trace[1037920028] 'agreement among raft nodes before linearized reading' (duration: 142.62889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:13.546154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.468612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/local-path-storage/\" range_end:\"/registry/networkpolicies/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:48:13.546211Z","caller":"traceutil/trace.go:171","msg":"trace[497272412] range","detail":"{range_begin:/registry/networkpolicies/local-path-storage/; range_end:/registry/networkpolicies/local-path-storage0; response_count:0; response_revision:1076; }","duration":"109.539453ms","start":"2026-02-11T01:48:13.436655Z","end":"2026-02-11T01:48:13.546194Z","steps":["trace[497272412] 'agreement among raft nodes before linearized reading' (duration: 109.441192ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:14.452224Z","caller":"traceutil/trace.go:171","msg":"trace[1966511450] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"122.04469ms","start":"2026-02-11T01:48:14.330138Z","end":"2026-02-11T01:48:14.452183Z","steps":["trace[1966511450] 'process raft request' (duration: 121.867697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:14.61389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.372103ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T01:48:14.614495Z","caller":"traceutil/trace.go:171","msg":"trace[432148242] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"264.973801ms","start":"2026-02-11T01:48:14.349502Z","end":"2026-02-11T01:48:14.614476Z","steps":["trace[432148242] 'read index received' (duration: 102.601912ms)","trace[432148242] 'applied index is now lower than readState.Index' (duration: 162.369879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:14.614882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.349616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-02-11T01:48:14.614937Z","caller":"traceutil/trace.go:171","msg":"trace[384513081] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:1078; }","duration":"265.455988ms","start":"2026-02-11T01:48:14.349468Z","end":"2026-02-11T01:48:14.614924Z","steps":["trace[384513081] 'agreement among raft nodes before linearized reading' (duration: 265.323596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:14.615222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.174982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-11T01:48:14.615358Z","caller":"traceutil/trace.go:171","msg":"trace[1766721111] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1078; }","duration":"206.295104ms","start":"2026-02-11T01:48:14.409003Z","end":"2026-02-11T01:48:14.615298Z","steps":["trace[1766721111] 'agreement among raft nodes before linearized reading' (duration: 205.915327ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:17.603106Z","caller":"traceutil/trace.go:171","msg":"trace[475641692] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"153.026136ms","start":"2026-02-11T01:48:17.450063Z","end":"2026-02-11T01:48:17.603089Z","steps":["trace[475641692] 'read index received' (duration: 103.015579ms)","trace[475641692] 'applied index is now lower than readState.Index' (duration: 50.009587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:17.603496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.412492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-11T01:48:17.603675Z","caller":"traceutil/trace.go:171","msg":"trace[1897186115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:1087; }","duration":"153.617745ms","start":"2026-02-11T01:48:17.450048Z","end":"2026-02-11T01:48:17.603666Z","steps":["trace[1897186115] 'agreement among raft nodes before linearized reading' (duration: 153.29699ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:17.60357Z","caller":"traceutil/trace.go:171","msg":"trace[1077163946] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"161.04031ms","start":"2026-02-11T01:48:17.44251Z","end":"2026-02-11T01:48:17.60355Z","steps":["trace[1077163946] 'process raft request' (duration: 110.629237ms)","trace[1077163946] 'compare' (duration: 49.863915ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:17.60425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.090988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-11T01:48:17.604845Z","caller":"traceutil/trace.go:171","msg":"trace[301593955] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:1087; }","duration":"124.705418ms","start":"2026-02-11T01:48:17.480121Z","end":"2026-02-11T01:48:17.604826Z","steps":["trace[301593955] 'agreement among raft nodes before linearized reading' (duration: 124.056348ms)"],"step_count":1}