{"level":"warn","ts":"2026-02-14T05:00:03.448864Z","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-14T05:00:03.448967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-14T05:00:03.449042Z","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-14T05:00:03.449055Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-02-14T05:00:03.449073Z","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-14T05:00:03.449708Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-02-14T05:00:03.44979Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-14T05:00:03.459472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.469684ms"} {"level":"info","ts":"2026-02-14T05:00:03.48232Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-02-14T05:00:03.482376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T05:00:03.482391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-02-14T05:00:03.482399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T05:00:03.482407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-02-14T05:00:03.482477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-02-14T05:00:03.493831Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T05:00:03.500431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T05:00:03.507346Z","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-14T05:00:03.511828Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T05:00:03.518832Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T05:00:03.519026Z","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-14T05:00:03.519056Z","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-14T05:00:03.519062Z","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-14T05:00:03.519336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-02-14T05:00:03.51945Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-02-14T05:00:03.530681Z","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-14T05:00:03.530847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T05:00:03.530871Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T05:00:03.530963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-14T05:00:03.530976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-14T05:00:03.585822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T05:00:03.58589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T05:00:03.585908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-02-14T05:00:03.585919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-02-14T05:00:03.585924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-14T05:00:03.585946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-02-14T05:00:03.585954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-14T05:00:03.588181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T05:00:03.589184Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T05:00:03.589278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T05:00:03.589952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T05:00:03.59Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T05:00:03.590025Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T05:00:03.590246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T05:00:03.592356Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-02-14T05:00:03.597659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T05:00:03.600422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T05:00:03.600438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T05:00:05.903457Z","caller":"traceutil/trace.go:171","msg":"trace[1472462713] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"119.248934ms","start":"2026-02-14T05:00:05.784171Z","end":"2026-02-14T05:00:05.90342Z","steps":["trace[1472462713] 'process raft request' (duration: 92.093307ms)","trace[1472462713] 'compare' (duration: 26.957022ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:05.90377Z","caller":"traceutil/trace.go:171","msg":"trace[544924138] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"119.508921ms","start":"2026-02-14T05:00:05.784246Z","end":"2026-02-14T05:00:05.903755Z","steps":["trace[544924138] 'process raft request' (duration: 119.114491ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.903882Z","caller":"traceutil/trace.go:171","msg":"trace[27901947] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"119.568893ms","start":"2026-02-14T05:00:05.784308Z","end":"2026-02-14T05:00:05.903877Z","steps":["trace[27901947] 'process raft request' (duration: 119.07785ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.904017Z","caller":"traceutil/trace.go:171","msg":"trace[90966557] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"119.506411ms","start":"2026-02-14T05:00:05.784506Z","end":"2026-02-14T05:00:05.904012Z","steps":["trace[90966557] 'process raft request' (duration: 118.902865ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.904105Z","caller":"traceutil/trace.go:171","msg":"trace[5236599] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"118.689509ms","start":"2026-02-14T05:00:05.785412Z","end":"2026-02-14T05:00:05.904101Z","steps":["trace[5236599] 'process raft request' (duration: 118.054852ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.904216Z","caller":"traceutil/trace.go:171","msg":"trace[1578193948] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"118.33718ms","start":"2026-02-14T05:00:05.785874Z","end":"2026-02-14T05:00:05.904211Z","steps":["trace[1578193948] 'process raft request' (duration: 117.611021ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.904297Z","caller":"traceutil/trace.go:171","msg":"trace[397020263] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"117.518319ms","start":"2026-02-14T05:00:05.786774Z","end":"2026-02-14T05:00:05.904293Z","steps":["trace[397020263] 'process raft request' (duration: 116.733538ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:05.904409Z","caller":"traceutil/trace.go:171","msg":"trace[1660916352] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"116.721987ms","start":"2026-02-14T05:00:05.787683Z","end":"2026-02-14T05:00:05.904405Z","steps":["trace[1660916352] 'process raft request' (duration: 115.844023ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.146672Z","caller":"traceutil/trace.go:171","msg":"trace[1351757596] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"152.353551ms","start":"2026-02-14T05:00:05.994301Z","end":"2026-02-14T05:00:06.146655Z","steps":["trace[1351757596] 'process raft request' (duration: 152.328481ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.14693Z","caller":"traceutil/trace.go:171","msg":"trace[209234586] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"217.734924ms","start":"2026-02-14T05:00:05.929187Z","end":"2026-02-14T05:00:06.146922Z","steps":["trace[209234586] 'process raft request' (duration: 208.740722ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.147022Z","caller":"traceutil/trace.go:171","msg":"trace[258899802] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"152.998339ms","start":"2026-02-14T05:00:05.994016Z","end":"2026-02-14T05:00:06.147015Z","steps":["trace[258899802] 'process raft request' (duration: 152.536047ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.147083Z","caller":"traceutil/trace.go:171","msg":"trace[1672330686] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"152.996809ms","start":"2026-02-14T05:00:05.994081Z","end":"2026-02-14T05:00:06.147078Z","steps":["trace[1672330686] 'process raft request' (duration: 152.491075ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.147141Z","caller":"traceutil/trace.go:171","msg":"trace[363329562] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"153.018279ms","start":"2026-02-14T05:00:05.994117Z","end":"2026-02-14T05:00:06.147135Z","steps":["trace[363329562] 'process raft request' (duration: 152.471455ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:06.14729Z","caller":"traceutil/trace.go:171","msg":"trace[917854760] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"153.002449ms","start":"2026-02-14T05:00:05.994262Z","end":"2026-02-14T05:00:06.147264Z","steps":["trace[917854760] 'process raft request' (duration: 152.341841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:09.097653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.94046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-14T05:00:09.097777Z","caller":"traceutil/trace.go:171","msg":"trace[1529884229] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:236; }","duration":"149.090864ms","start":"2026-02-14T05:00:08.948671Z","end":"2026-02-14T05:00:09.097762Z","steps":["trace[1529884229] 'agreement among raft nodes before linearized reading' (duration: 148.903599ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:13.510658Z","caller":"traceutil/trace.go:171","msg":"trace[1842957428] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"144.211314ms","start":"2026-02-14T05:00:13.366431Z","end":"2026-02-14T05:00:13.510642Z","steps":["trace[1842957428] 'process raft request' (duration: 144.179203ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:13.51071Z","caller":"traceutil/trace.go:171","msg":"trace[840117466] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"157.981952ms","start":"2026-02-14T05:00:13.35269Z","end":"2026-02-14T05:00:13.510672Z","steps":["trace[840117466] 'process raft request' (duration: 155.096495ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:19.375884Z","caller":"traceutil/trace.go:171","msg":"trace[228210037] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"136.075144ms","start":"2026-02-14T05:00:19.23979Z","end":"2026-02-14T05:00:19.375865Z","steps":["trace[228210037] 'process raft request' (duration: 135.895439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.385539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.806846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:22.385661Z","caller":"traceutil/trace.go:171","msg":"trace[328497313] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"308.833824ms","start":"2026-02-14T05:00:22.076813Z","end":"2026-02-14T05:00:22.385646Z","steps":["trace[328497313] 'read index received' (duration: 162.158344ms)","trace[328497313] 'applied index is now lower than readState.Index' (duration: 146.67433ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:22.386286Z","caller":"traceutil/trace.go:171","msg":"trace[564015118] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"312.84976ms","start":"2026-02-14T05:00:22.073422Z","end":"2026-02-14T05:00:22.386272Z","steps":["trace[564015118] 'process raft request' (duration: 165.600886ms)","trace[564015118] 'compare' (duration: 145.646581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:22.386489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.073408Z","time spent":"312.995175ms","remote":"127.0.0.1:47120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:22.386929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.086657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-14T05:00:22.387024Z","caller":"traceutil/trace.go:171","msg":"trace[1512838202] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:404; }","duration":"310.22106ms","start":"2026-02-14T05:00:22.076792Z","end":"2026-02-14T05:00:22.387013Z","steps":["trace[1512838202] 'agreement among raft nodes before linearized reading' (duration: 310.057566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.387129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.076786Z","time spent":"310.331083ms","remote":"127.0.0.1:47106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4197,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-14T05:00:22.387559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.437558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:22.389364Z","caller":"traceutil/trace.go:171","msg":"trace[567818153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"184.139473ms","start":"2026-02-14T05:00:22.205086Z","end":"2026-02-14T05:00:22.389226Z","steps":["trace[567818153] 'agreement among raft nodes before linearized reading' (duration: 182.440597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:22.907433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194724735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:00:23.407809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194724735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:00:23.859345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.454012651s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-14T05:00:24.745605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T05:00:24.745705Z","caller":"traceutil/trace.go:171","msg":"trace[1715177560] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000334747s","start":"2026-02-14T05:00:22.745352Z","end":"2026-02-14T05:00:24.745686Z","steps":["trace[1715177560] 'agreement among raft nodes before linearized reading' (duration: 2.000215594s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:24.745762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.745336Z","time spent":"2.000407709s","remote":"127.0.0.1:46686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:00:25.246488Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.386979367s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-14T05:00:25.336409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476749712s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:25.336837Z","caller":"traceutil/trace.go:171","msg":"trace[1040793080] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"2.931583884s","start":"2026-02-14T05:00:22.405207Z","end":"2026-02-14T05:00:25.336791Z","steps":["trace[1040793080] 'process raft request' (duration: 1.45436867s)","trace[1040793080] 'compare' (duration: 1.475833107s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:25.336992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.405193Z","time spent":"2.931711398s","remote":"127.0.0.1:47106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:26.25032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.810641ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:00:26.250633Z","caller":"traceutil/trace.go:171","msg":"trace[1336791106] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"3.84406835s","start":"2026-02-14T05:00:22.406497Z","end":"2026-02-14T05:00:26.250565Z","steps":["trace[1336791106] 'read index received' (duration: 1.453088977s)","trace[1336791106] 'applied index is now lower than readState.Index' (duration: 2.390978493s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:26.251195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.844677546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-14T05:00:26.251264Z","caller":"traceutil/trace.go:171","msg":"trace[2035131062] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:405; }","duration":"3.844772168s","start":"2026-02-14T05:00:22.40647Z","end":"2026-02-14T05:00:26.251242Z","steps":["trace[2035131062] 'agreement among raft nodes before linearized reading' (duration: 3.844643435s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:26.251352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:22.406464Z","time spent":"3.844879461s","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7252,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-14T05:00:26.251572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994621425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T05:00:26.251611Z","caller":"traceutil/trace.go:171","msg":"trace[1991009768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"2.994695287s","start":"2026-02-14T05:00:23.256905Z","end":"2026-02-14T05:00:26.2516Z","steps":["trace[1991009768] 'agreement among raft nodes before linearized reading' (duration: 2.994561843s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:26.251646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:23.25689Z","time spent":"2.994746638s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T05:00:26.251721Z","caller":"traceutil/trace.go:171","msg":"trace[1147684865] transaction","detail":"{read_only:false; number_of_response:1; response_revision:406; }","duration":"902.050416ms","start":"2026-02-14T05:00:25.349658Z","end":"2026-02-14T05:00:26.251708Z","steps":["trace[1147684865] 'process raft request' (duration: 901.756578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:26.251803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:25.349638Z","time spent":"902.115407ms","remote":"127.0.0.1:47106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":4208,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:26.251837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49609454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:26.251884Z","caller":"traceutil/trace.go:171","msg":"trace[2004563695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"1.496152141s","start":"2026-02-14T05:00:24.755711Z","end":"2026-02-14T05:00:26.251863Z","steps":["trace[2004563695] 'agreement among raft nodes before linearized reading' (duration: 1.496095179s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:26.251912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:24.755701Z","time spent":"1.496204353s","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T05:00:26.252201Z","caller":"traceutil/trace.go:171","msg":"trace[1360088750] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.495142864s","start":"2026-02-14T05:00:24.757048Z","end":"2026-02-14T05:00:26.252191Z","steps":["trace[1360088750] 'process raft request' (duration: 1.493366366s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:26.252267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:24.757038Z","time spent":"1.495189136s","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T05:00:26.312994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751883542s","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-14T05:00:26.313237Z","caller":"traceutil/trace.go:171","msg":"trace[1647245036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"2.752181409s","start":"2026-02-14T05:00:23.561033Z","end":"2026-02-14T05:00:26.313214Z","steps":["trace[1647245036] 'agreement among raft nodes before linearized reading' (duration: 2.69098647s)","trace[1647245036] 'range keys from in-memory index tree' (duration: 60.913312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:26.313336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:23.561015Z","time spent":"2.752310303s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:26.313566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.273061764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:00:26.313628Z","caller":"traceutil/trace.go:171","msg":"trace[842475179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"2.273144046s","start":"2026-02-14T05:00:24.040475Z","end":"2026-02-14T05:00:26.313619Z","steps":["trace[842475179] 'agreement among raft nodes before linearized reading' (duration: 2.211536156s)","trace[842475179] 'range keys from in-memory index tree' (duration: 61.529308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:26.313688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:24.040462Z","time spent":"2.273201788s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:00:26.313178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.862989277s","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-14T05:00:26.31422Z","caller":"traceutil/trace.go:171","msg":"trace[496027393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"2.864069496s","start":"2026-02-14T05:00:23.450124Z","end":"2026-02-14T05:00:26.314194Z","steps":["trace[496027393] 'agreement among raft nodes before linearized reading' (duration: 2.801901301s)","trace[496027393] 'range keys from in-memory index tree' (duration: 61.095957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:26.314345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:23.450106Z","time spent":"2.864210861s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T05:00:36.919475Z","caller":"traceutil/trace.go:171","msg":"trace[529272356] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"144.08378ms","start":"2026-02-14T05:00:36.775365Z","end":"2026-02-14T05:00:36.919449Z","steps":["trace[529272356] 'process raft request' (duration: 143.847944ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:40.130756Z","caller":"traceutil/trace.go:171","msg":"trace[808649870] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"277.685179ms","start":"2026-02-14T05:00:39.853048Z","end":"2026-02-14T05:00:40.130733Z","steps":["trace[808649870] 'process raft request' (duration: 277.510794ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:40.607151Z","caller":"traceutil/trace.go:171","msg":"trace[96562533] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"387.902312ms","start":"2026-02-14T05:00:40.219235Z","end":"2026-02-14T05:00:40.607137Z","steps":["trace[96562533] 'read index received' (duration: 326.909068ms)","trace[96562533] 'applied index is now lower than readState.Index' (duration: 60.992694ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:40.607183Z","caller":"traceutil/trace.go:171","msg":"trace[822744564] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"602.251714ms","start":"2026-02-14T05:00:40.004912Z","end":"2026-02-14T05:00:40.607164Z","steps":["trace[822744564] 'process raft request' (duration: 541.280421ms)","trace[822744564] 'compare' (duration: 60.668215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:40.607297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.004894Z","time spent":"602.338506ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:40.607609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.042528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-14T05:00:40.607627Z","caller":"traceutil/trace.go:171","msg":"trace[1577494317] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:509; }","duration":"188.10423ms","start":"2026-02-14T05:00:40.419517Z","end":"2026-02-14T05:00:40.607621Z","steps":["trace[1577494317] 'agreement among raft nodes before linearized reading' (duration: 188.033838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.60773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.499867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-14T05:00:40.607748Z","caller":"traceutil/trace.go:171","msg":"trace[813826460] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:509; }","duration":"388.536069ms","start":"2026-02-14T05:00:40.219203Z","end":"2026-02-14T05:00:40.607739Z","steps":["trace[813826460] 'agreement among raft nodes before linearized reading' (duration: 388.498608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.607761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.219178Z","time spent":"388.57953ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-14T05:00:40.608037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.733624ms","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-14T05:00:40.608051Z","caller":"traceutil/trace.go:171","msg":"trace[1090328716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:509; }","duration":"170.785246ms","start":"2026-02-14T05:00:40.437262Z","end":"2026-02-14T05:00:40.608047Z","steps":["trace[1090328716] 'agreement among raft nodes before linearized reading' (duration: 170.749015ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:40.611255Z","caller":"traceutil/trace.go:171","msg":"trace[2075656355] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"587.012556ms","start":"2026-02-14T05:00:40.020246Z","end":"2026-02-14T05:00:40.607258Z","steps":["trace[2075656355] 'process raft request' (duration: 586.862982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:40.611364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.020221Z","time spent":"591.091065ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T05:00:41.126948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194724943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T05:00:41.295976Z","caller":"traceutil/trace.go:171","msg":"trace[183116154] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"683.562172ms","start":"2026-02-14T05:00:40.612394Z","end":"2026-02-14T05:00:41.295956Z","steps":["trace[183116154] 'process raft request' (duration: 683.405137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.296125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.612379Z","time spent":"683.652284ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:41.301134Z","caller":"traceutil/trace.go:171","msg":"trace[267605276] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:526; }","duration":"674.516519ms","start":"2026-02-14T05:00:40.626605Z","end":"2026-02-14T05:00:41.301121Z","steps":["trace[267605276] 'read index received' (duration: 669.248578ms)","trace[267605276] 'applied index is now lower than readState.Index' (duration: 5.267091ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:41.301333Z","caller":"traceutil/trace.go:171","msg":"trace[956582453] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"279.078556ms","start":"2026-02-14T05:00:41.022247Z","end":"2026-02-14T05:00:41.301325Z","steps":["trace[956582453] 'process raft request' (duration: 278.85078ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:41.301343Z","caller":"traceutil/trace.go:171","msg":"trace[1184397524] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"679.880882ms","start":"2026-02-14T05:00:40.62138Z","end":"2026-02-14T05:00:41.301261Z","steps":["trace[1184397524] 'process raft request' (duration: 679.697748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.301497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.891729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-02-14T05:00:41.301468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.621372Z","time spent":"680.017236ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:41.301522Z","caller":"traceutil/trace.go:171","msg":"trace[656195403] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"674.92902ms","start":"2026-02-14T05:00:40.626584Z","end":"2026-02-14T05:00:41.301513Z","steps":["trace[656195403] 'agreement among raft nodes before linearized reading' (duration: 674.846608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.301541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.626576Z","time spent":"674.960551ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-14T05:00:41.301644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.324635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-14T05:00:41.301661Z","caller":"traceutil/trace.go:171","msg":"trace[1835537560] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"481.366856ms","start":"2026-02-14T05:00:40.820288Z","end":"2026-02-14T05:00:41.301655Z","steps":["trace[1835537560] 'agreement among raft nodes before linearized reading' (duration: 481.319575ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:41.301665Z","caller":"traceutil/trace.go:171","msg":"trace[1959690026] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"685.264197ms","start":"2026-02-14T05:00:40.616388Z","end":"2026-02-14T05:00:41.301653Z","steps":["trace[1959690026] 'process raft request' (duration: 684.561528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.301725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.616379Z","time spent":"685.315418ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:41.301873Z","caller":"traceutil/trace.go:171","msg":"trace[1641587845] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"683.701315ms","start":"2026-02-14T05:00:40.618136Z","end":"2026-02-14T05:00:41.301837Z","steps":["trace[1641587845] 'process raft request' (duration: 682.883593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.301927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.618119Z","time spent":"683.778387ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:41.302065Z","caller":"traceutil/trace.go:171","msg":"trace[839709897] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"683.833838ms","start":"2026-02-14T05:00:40.618225Z","end":"2026-02-14T05:00:41.302059Z","steps":["trace[839709897] 'process raft request' (duration: 682.813681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.302104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.61822Z","time spent":"683.86225ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:41.302149Z","caller":"traceutil/trace.go:171","msg":"trace[1310155741] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"682.943964ms","start":"2026-02-14T05:00:40.6192Z","end":"2026-02-14T05:00:41.302144Z","steps":["trace[1310155741] 'process raft request' (duration: 681.859705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:41.302175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.619194Z","time spent":"682.966636ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:41.301677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:40.820274Z","time spent":"481.399157ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-14T05:00:47.108683Z","caller":"traceutil/trace.go:171","msg":"trace[734918600] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"163.299465ms","start":"2026-02-14T05:00:46.945146Z","end":"2026-02-14T05:00:47.108446Z","steps":["trace[734918600] 'process raft request' (duration: 163.12397ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:48.293589Z","caller":"traceutil/trace.go:171","msg":"trace[38152481] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"201.907099ms","start":"2026-02-14T05:00:48.091665Z","end":"2026-02-14T05:00:48.293572Z","steps":["trace[38152481] 'process raft request' (duration: 201.813546ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:49.52605Z","caller":"traceutil/trace.go:171","msg":"trace[1214763268] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"179.083198ms","start":"2026-02-14T05:00:49.346951Z","end":"2026-02-14T05:00:49.526034Z","steps":["trace[1214763268] 'read index received' (duration: 178.937404ms)","trace[1214763268] 'applied index is now lower than readState.Index' (duration: 145.274µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:49.526142Z","caller":"traceutil/trace.go:171","msg":"trace[1100408768] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"181.922773ms","start":"2026-02-14T05:00:49.34421Z","end":"2026-02-14T05:00:49.526133Z","steps":["trace[1100408768] 'process raft request' (duration: 181.698367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.526317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.352055ms","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-14T05:00:49.526339Z","caller":"traceutil/trace.go:171","msg":"trace[477410943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"179.404016ms","start":"2026-02-14T05:00:49.346928Z","end":"2026-02-14T05:00:49.526332Z","steps":["trace[477410943] 'agreement among raft nodes before linearized reading' (duration: 179.300443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.526423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.609671ms","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-14T05:00:49.52644Z","caller":"traceutil/trace.go:171","msg":"trace[1364434251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"176.637402ms","start":"2026-02-14T05:00:49.349796Z","end":"2026-02-14T05:00:49.526434Z","steps":["trace[1364434251] 'agreement among raft nodes before linearized reading' (duration: 176.594351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.526558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.254524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-14T05:00:49.52659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.328933ms","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-14T05:00:49.526608Z","caller":"traceutil/trace.go:171","msg":"trace[1987326206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:551; }","duration":"169.324956ms","start":"2026-02-14T05:00:49.35727Z","end":"2026-02-14T05:00:49.526595Z","steps":["trace[1987326206] 'agreement among raft nodes before linearized reading' (duration: 169.188152ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:49.526613Z","caller":"traceutil/trace.go:171","msg":"trace[2143694630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"176.361515ms","start":"2026-02-14T05:00:49.350243Z","end":"2026-02-14T05:00:49.526605Z","steps":["trace[2143694630] 'agreement among raft nodes before linearized reading' (duration: 176.307264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.987296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.361727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:49.987366Z","caller":"traceutil/trace.go:171","msg":"trace[1783304141] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"456.54352ms","start":"2026-02-14T05:00:49.53081Z","end":"2026-02-14T05:00:49.987353Z","steps":["trace[1783304141] 'process raft request' (duration: 100.06317ms)","trace[1783304141] 'compare' (duration: 356.228934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:49.987396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:49.530796Z","time spent":"456.587312ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:50.005532Z","caller":"traceutil/trace.go:171","msg":"trace[2063282610] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"473.725191ms","start":"2026-02-14T05:00:49.531779Z","end":"2026-02-14T05:00:50.005504Z","steps":["trace[2063282610] 'process raft request' (duration: 473.67663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:50.005642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:49.531772Z","time spent":"473.807344ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:50.005642Z","caller":"traceutil/trace.go:171","msg":"trace[1886528443] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"474.634795ms","start":"2026-02-14T05:00:49.531001Z","end":"2026-02-14T05:00:50.005636Z","steps":["trace[1886528443] 'process raft request' (duration: 456.574561ms)","trace[1886528443] 'compare' (duration: 17.765027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:50.005695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:49.530993Z","time spent":"474.680666ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:00:50.005553Z","caller":"traceutil/trace.go:171","msg":"trace[422272858] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"472.146209ms","start":"2026-02-14T05:00:49.533399Z","end":"2026-02-14T05:00:50.005545Z","steps":["trace[422272858] 'process raft request' (duration: 472.083737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:50.005748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:49.533393Z","time spent":"472.343634ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:50.506762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.753499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:50.506833Z","caller":"traceutil/trace.go:171","msg":"trace[1321336029] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"252.885324ms","start":"2026-02-14T05:00:50.253928Z","end":"2026-02-14T05:00:50.506813Z","steps":["trace[1321336029] 'process raft request' (duration: 18.021503ms)","trace[1321336029] 'compare' (duration: 234.551973ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:54.146784Z","caller":"traceutil/trace.go:171","msg":"trace[1732430134] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"118.624748ms","start":"2026-02-14T05:00:54.028137Z","end":"2026-02-14T05:00:54.146762Z","steps":["trace[1732430134] 'process raft request' (duration: 118.565126ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:54.146994Z","caller":"traceutil/trace.go:171","msg":"trace[648780084] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"118.895715ms","start":"2026-02-14T05:00:54.028082Z","end":"2026-02-14T05:00:54.146978Z","steps":["trace[648780084] 'process raft request' (duration: 45.281393ms)","trace[648780084] 'compare' (duration: 73.179141ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:54.372455Z","caller":"traceutil/trace.go:171","msg":"trace[1661466835] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"254.110727ms","start":"2026-02-14T05:00:54.118322Z","end":"2026-02-14T05:00:54.372432Z","steps":["trace[1661466835] 'process raft request' (duration: 207.238051ms)","trace[1661466835] 'compare' (duration: 46.736922ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:54.440655Z","caller":"traceutil/trace.go:171","msg":"trace[1469110797] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"273.122657ms","start":"2026-02-14T05:00:54.167508Z","end":"2026-02-14T05:00:54.44063Z","steps":["trace[1469110797] 'process raft request' (duration: 273.000274ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:55.08702Z","caller":"traceutil/trace.go:171","msg":"trace[1663794030] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"125.435369ms","start":"2026-02-14T05:00:54.961565Z","end":"2026-02-14T05:00:55.087Z","steps":["trace[1663794030] 'process raft request' (duration: 125.285865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.794836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.358968ms","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-14T05:01:00.794903Z","caller":"traceutil/trace.go:171","msg":"trace[1224067974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"667.474211ms","start":"2026-02-14T05:01:00.127416Z","end":"2026-02-14T05:01:00.79489Z","steps":["trace[1224067974] 'range keys from in-memory index tree' (duration: 667.203585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.794938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.127394Z","time spent":"667.538443ms","remote":"127.0.0.1:46912","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-14T05:01:00.79513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.384539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T05:01:00.795146Z","caller":"traceutil/trace.go:171","msg":"trace[1470998890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"614.450371ms","start":"2026-02-14T05:01:00.18069Z","end":"2026-02-14T05:01:00.79514Z","steps":["trace[1470998890] 'range keys from in-memory index tree' (duration: 614.297347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.795188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.180667Z","time spent":"614.487842ms","remote":"127.0.0.1:46912","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-14T05:01:00.79537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.934767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T05:01:00.795385Z","caller":"traceutil/trace.go:171","msg":"trace[1367849986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:612; }","duration":"607.976127ms","start":"2026-02-14T05:01:00.187404Z","end":"2026-02-14T05:01:00.79538Z","steps":["trace[1367849986] 'range keys from in-memory index tree' (duration: 607.863255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.795429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.187395Z","time spent":"608.001289ms","remote":"127.0.0.1:46912","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-14T05:01:00.795612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.184083ms","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-14T05:01:00.795687Z","caller":"traceutil/trace.go:171","msg":"trace[1140292267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"602.325896ms","start":"2026-02-14T05:01:00.193341Z","end":"2026-02-14T05:01:00.795667Z","steps":["trace[1140292267] 'range keys from in-memory index tree' (duration: 601.935175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.795716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.193319Z","time spent":"602.390268ms","remote":"127.0.0.1:46912","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-14T05:01:01.298987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194725187,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:01:01.800145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194725187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T05:01:01.928127Z","caller":"traceutil/trace.go:171","msg":"trace[850765506] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.149907715s","start":"2026-02-14T05:01:00.778204Z","end":"2026-02-14T05:01:01.928111Z","steps":["trace[850765506] 'process raft request' (duration: 1.149788712s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:01.928232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.778172Z","time spent":"1.150002328s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:02.798052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999262917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T05:01:02.798116Z","caller":"traceutil/trace.go:171","msg":"trace[97420938] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99935457s","start":"2026-02-14T05:01:00.798747Z","end":"2026-02-14T05:01:02.798102Z","steps":["trace[97420938] 'agreement among raft nodes before linearized reading' (duration: 1.999268588s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:02.798148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.798732Z","time spent":"1.999409391s","remote":"127.0.0.1:46686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/14 05:01:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-14T05:01:03.063539Z","caller":"traceutil/trace.go:171","msg":"trace[1546335037] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:634; }","duration":"2.264761279s","start":"2026-02-14T05:01:00.798761Z","end":"2026-02-14T05:01:03.063523Z","steps":["trace[1546335037] 'read index received' (duration: 1.129258322s)","trace[1546335037] 'applied index is now lower than readState.Index' (duration: 1.135502177s)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:03.063723Z","caller":"traceutil/trace.go:171","msg":"trace[11151306] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.262104628s","start":"2026-02-14T05:01:00.801613Z","end":"2026-02-14T05:01:03.063717Z","steps":["trace[11151306] 'process raft request' (duration: 2.261606015s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.063783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.801592Z","time spent":"2.262145329s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:01:03.063886Z","caller":"traceutil/trace.go:171","msg":"trace[952849976] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.256895579s","start":"2026-02-14T05:01:00.806986Z","end":"2026-02-14T05:01:03.063882Z","steps":["trace[952849976] 'process raft request' (duration: 2.256473058s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.063911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.806964Z","time spent":"2.256932749s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:01:03.064008Z","caller":"traceutil/trace.go:171","msg":"trace[34541683] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.25695399s","start":"2026-02-14T05:01:00.807049Z","end":"2026-02-14T05:01:03.064003Z","steps":["trace[34541683] 'process raft request' (duration: 2.256431426s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.064031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.807044Z","time spent":"2.256973571s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:01:03.064088Z","caller":"traceutil/trace.go:171","msg":"trace[257389815] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.256917639s","start":"2026-02-14T05:01:00.807165Z","end":"2026-02-14T05:01:03.064082Z","steps":["trace[257389815] 'process raft request' (duration: 2.256332814s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.06411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.807159Z","time spent":"2.25693762s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:03.226223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.681861ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:01:03.226292Z","caller":"traceutil/trace.go:171","msg":"trace[1280336176] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"162.702609ms","start":"2026-02-14T05:01:03.063578Z","end":"2026-02-14T05:01:03.226281Z","steps":["trace[1280336176] 'read index received' (duration: 31.953866ms)","trace[1280336176] 'applied index is now lower than readState.Index' (duration: 130.747893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:03.226412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.382280058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hfsm4\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-14T05:01:03.226428Z","caller":"traceutil/trace.go:171","msg":"trace[2063303200] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hfsm4; range_end:; response_count:1; response_revision:617; }","duration":"2.382304818s","start":"2026-02-14T05:01:00.844118Z","end":"2026-02-14T05:01:03.226423Z","steps":["trace[2063303200] 'agreement among raft nodes before linearized reading' (duration: 2.382189585s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.226447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.84406Z","time spent":"2.382381971s","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18270,"request content":"key:\"/registry/pods/kube-system/cilium-hfsm4\" "} {"level":"warn","ts":"2026-02-14T05:01:03.226726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.186756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:03.22674Z","caller":"traceutil/trace.go:171","msg":"trace[2120236104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"423.208377ms","start":"2026-02-14T05:01:02.803528Z","end":"2026-02-14T05:01:03.226736Z","steps":["trace[2120236104] 'agreement among raft nodes before linearized reading' (duration: 423.183906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.226761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:02.803523Z","time spent":"423.234887ms","remote":"127.0.0.1:46686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:01:03.226842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.3101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1894043f9e2ba01c\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-14T05:01:03.226855Z","caller":"traceutil/trace.go:171","msg":"trace[564926420] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1894043f9e2ba01c; range_end:; response_count:1; response_revision:617; }","duration":"426.339541ms","start":"2026-02-14T05:01:02.800511Z","end":"2026-02-14T05:01:03.226851Z","steps":["trace[564926420] 'agreement among raft nodes before linearized reading' (duration: 426.30438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.226867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:02.800499Z","time spent":"426.365011ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1894043f9e2ba01c\" "} {"level":"warn","ts":"2026-02-14T05:01:03.226945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196872731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:03.226956Z","caller":"traceutil/trace.go:171","msg":"trace[442712213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"1.196915372s","start":"2026-02-14T05:01:02.030037Z","end":"2026-02-14T05:01:03.226952Z","steps":["trace[442712213] 'agreement among raft nodes before linearized reading' (duration: 1.196897972s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.226966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:02.029977Z","time spent":"1.196987055s","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:01:03.227049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558052648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:01:03.227061Z","caller":"traceutil/trace.go:171","msg":"trace[29595279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"1.558064608s","start":"2026-02-14T05:01:01.668993Z","end":"2026-02-14T05:01:03.227058Z","steps":["trace[29595279] 'agreement among raft nodes before linearized reading' (duration: 1.558026657s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:03.227072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:01.668954Z","time spent":"1.55811539s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:01:12.970629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.986297ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-14T05:01:13.644832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194725341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T05:01:14.02826Z","caller":"traceutil/trace.go:171","msg":"trace[825486443] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"886.490648ms","start":"2026-02-14T05:01:13.141753Z","end":"2026-02-14T05:01:14.028244Z","steps":["trace[825486443] 'process raft request' (duration: 886.395235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.028367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.141741Z","time spent":"886.569999ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:14.370632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.788812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:01:14.370794Z","caller":"traceutil/trace.go:171","msg":"trace[1244655560] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"1.22632381s","start":"2026-02-14T05:01:13.144461Z","end":"2026-02-14T05:01:14.370785Z","steps":["trace[1244655560] 'read index received' (duration: 883.853386ms)","trace[1244655560] 'applied index is now lower than readState.Index' (duration: 342.469914ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:14.370846Z","caller":"traceutil/trace.go:171","msg":"trace[513295513] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.22746401s","start":"2026-02-14T05:01:13.143374Z","end":"2026-02-14T05:01:14.370838Z","steps":["trace[513295513] 'process raft request' (duration: 943.420942ms)","trace[513295513] 'compare' (duration: 283.704219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:14.370878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.143359Z","time spent":"1.227501441s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:14.371101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041000514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:01:14.371119Z","caller":"traceutil/trace.go:171","msg":"trace[1390316569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"1.041055255s","start":"2026-02-14T05:01:13.330058Z","end":"2026-02-14T05:01:14.371113Z","steps":["trace[1390316569] 'agreement among raft nodes before linearized reading' (duration: 1.040982054s)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:14.371134Z","caller":"traceutil/trace.go:171","msg":"trace[856684871] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.225836517s","start":"2026-02-14T05:01:13.145283Z","end":"2026-02-14T05:01:14.371119Z","steps":["trace[856684871] 'process raft request' (duration: 1.225416525s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.371197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.387201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:14.371212Z","caller":"traceutil/trace.go:171","msg":"trace[1465092803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"625.433932ms","start":"2026-02-14T05:01:13.745774Z","end":"2026-02-14T05:01:14.371208Z","steps":["trace[1465092803] 'agreement among raft nodes before linearized reading' (duration: 625.411891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.371215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.145268Z","time spent":"1.225898498s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:14.371253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.98984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:01:14.371275Z","caller":"traceutil/trace.go:171","msg":"trace[1059522465] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:700; }","duration":"656.021151ms","start":"2026-02-14T05:01:13.715241Z","end":"2026-02-14T05:01:14.371262Z","steps":["trace[1059522465] 'agreement among raft nodes before linearized reading' (duration: 655.998641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.371292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.71523Z","time spent":"656.053742ms","remote":"127.0.0.1:46898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:01:14.371379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.657599Z","time spent":"713.778509ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-14T05:01:14.371391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.4532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-q4s7t\" ","response":"range_response_count:1 size:5022"} {"level":"info","ts":"2026-02-14T05:01:14.371405Z","caller":"traceutil/trace.go:171","msg":"trace[635418256] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-q4s7t; range_end:; response_count:1; response_revision:700; }","duration":"713.48335ms","start":"2026-02-14T05:01:13.657918Z","end":"2026-02-14T05:01:14.371401Z","steps":["trace[635418256] 'agreement among raft nodes before linearized reading' (duration: 713.441519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.371417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.657909Z","time spent":"713.504862ms","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5046,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-q4s7t\" "} {"level":"warn","ts":"2026-02-14T05:01:14.371134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.330034Z","time spent":"1.041095787s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:01:14.371224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.745749Z","time spent":"625.471193ms","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:01:14.371068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226592377s","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-14T05:01:14.371652Z","caller":"traceutil/trace.go:171","msg":"trace[128550625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"1.227504592s","start":"2026-02-14T05:01:13.144138Z","end":"2026-02-14T05:01:14.371642Z","steps":["trace[128550625] 'agreement among raft nodes before linearized reading' (duration: 1.226815493s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:14.371677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:13.144128Z","time spent":"1.227542282s","remote":"127.0.0.1:46912","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-14T05:01:14.676419Z","caller":"traceutil/trace.go:171","msg":"trace[47805372] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"296.392089ms","start":"2026-02-14T05:01:14.380013Z","end":"2026-02-14T05:01:14.676405Z","steps":["trace[47805372] 'process raft request' (duration: 288.046036ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:14.676438Z","caller":"traceutil/trace.go:171","msg":"trace[959141145] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"293.169644ms","start":"2026-02-14T05:01:14.383248Z","end":"2026-02-14T05:01:14.676418Z","steps":["trace[959141145] 'process raft request' (duration: 293.133143ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:14.676594Z","caller":"traceutil/trace.go:171","msg":"trace[235998584] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"295.856325ms","start":"2026-02-14T05:01:14.380731Z","end":"2026-02-14T05:01:14.676587Z","steps":["trace[235998584] 'process raft request' (duration: 295.571178ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:14.870732Z","caller":"traceutil/trace.go:171","msg":"trace[899344619] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"100.328057ms","start":"2026-02-14T05:01:14.770387Z","end":"2026-02-14T05:01:14.870715Z","steps":["trace[899344619] 'process raft request' (duration: 100.217494ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:26.865775Z","caller":"traceutil/trace.go:171","msg":"trace[1334262082] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"136.499146ms","start":"2026-02-14T05:01:26.729258Z","end":"2026-02-14T05:01:26.865757Z","steps":["trace[1334262082] 'read index received' (duration: 134.968575ms)","trace[1334262082] 'applied index is now lower than readState.Index' (duration: 1.529751ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:26.865862Z","caller":"traceutil/trace.go:171","msg":"trace[1137092893] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"416.037449ms","start":"2026-02-14T05:01:26.449806Z","end":"2026-02-14T05:01:26.865844Z","steps":["trace[1137092893] 'process raft request' (duration: 414.466718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:26.865942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.67731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:01:26.865965Z","caller":"traceutil/trace.go:171","msg":"trace[1491386166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"136.761122ms","start":"2026-02-14T05:01:26.729197Z","end":"2026-02-14T05:01:26.865958Z","steps":["trace[1491386166] 'agreement among raft nodes before linearized reading' (duration: 136.656629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:26.865959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:26.449792Z","time spent":"416.112251ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:26.866067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.525792ms","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-14T05:01:26.866082Z","caller":"traceutil/trace.go:171","msg":"trace[197553108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"129.578543ms","start":"2026-02-14T05:01:26.736499Z","end":"2026-02-14T05:01:26.866078Z","steps":["trace[197553108] 'agreement among raft nodes before linearized reading' (duration: 129.533792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:26.8662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.843526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:26.866224Z","caller":"traceutil/trace.go:171","msg":"trace[75932264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"122.880537ms","start":"2026-02-14T05:01:26.743337Z","end":"2026-02-14T05:01:26.866217Z","steps":["trace[75932264] 'agreement among raft nodes before linearized reading' (duration: 122.834986ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:28.458225Z","caller":"traceutil/trace.go:171","msg":"trace[1027306591] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"354.108228ms","start":"2026-02-14T05:01:28.104097Z","end":"2026-02-14T05:01:28.458206Z","steps":["trace[1027306591] 'read index received' (duration: 353.945333ms)","trace[1027306591] 'applied index is now lower than readState.Index' (duration: 162.165µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:28.458331Z","caller":"traceutil/trace.go:171","msg":"trace[2039557559] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"491.093836ms","start":"2026-02-14T05:01:27.967228Z","end":"2026-02-14T05:01:28.458322Z","steps":["trace[2039557559] 'process raft request' (duration: 490.85357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:28.458462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:27.967211Z","time spent":"491.144078ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:28.458675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.5776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T05:01:28.458705Z","caller":"traceutil/trace.go:171","msg":"trace[2008400698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"354.632052ms","start":"2026-02-14T05:01:28.104064Z","end":"2026-02-14T05:01:28.458696Z","steps":["trace[2008400698] 'agreement among raft nodes before linearized reading' (duration: 354.527969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:28.458733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:28.104052Z","time spent":"354.676013ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T05:01:30.60218Z","caller":"traceutil/trace.go:171","msg":"trace[1025259928] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"115.454011ms","start":"2026-02-14T05:01:30.486702Z","end":"2026-02-14T05:01:30.602156Z","steps":["trace[1025259928] 'process raft request' (duration: 115.275426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.326506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.988665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:01:31.326604Z","caller":"traceutil/trace.go:171","msg":"trace[312011894] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"581.496556ms","start":"2026-02-14T05:01:30.745092Z","end":"2026-02-14T05:01:31.326589Z","steps":["trace[312011894] 'read index received' (duration: 58.269055ms)","trace[312011894] 'applied index is now lower than readState.Index' (duration: 523.226281ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:31.3268Z","caller":"traceutil/trace.go:171","msg":"trace[1513400897] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"839.640176ms","start":"2026-02-14T05:01:30.487146Z","end":"2026-02-14T05:01:31.326787Z","steps":["trace[1513400897] 'process raft request' (duration: 316.300412ms)","trace[1513400897] 'compare' (duration: 522.727478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:31.326884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:30.48713Z","time spent":"839.722088ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:31.327098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.01648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:31.327129Z","caller":"traceutil/trace.go:171","msg":"trace[1116276309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"582.057061ms","start":"2026-02-14T05:01:30.745061Z","end":"2026-02-14T05:01:31.327118Z","steps":["trace[1116276309] 'agreement among raft nodes before linearized reading' (duration: 582.00695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.327157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:30.745008Z","time spent":"582.143684ms","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:01:31.328328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.586938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:01:31.328385Z","caller":"traceutil/trace.go:171","msg":"trace[285283846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"442.678451ms","start":"2026-02-14T05:01:30.885687Z","end":"2026-02-14T05:01:31.328366Z","steps":["trace[285283846] 'agreement among raft nodes before linearized reading' (duration: 442.524447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.328423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:30.885664Z","time spent":"442.749452ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:01:31.32861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.595871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:01:31.328643Z","caller":"traceutil/trace.go:171","msg":"trace[415481520] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:755; }","duration":"220.657692ms","start":"2026-02-14T05:01:31.107974Z","end":"2026-02-14T05:01:31.328632Z","steps":["trace[415481520] 'agreement among raft nodes before linearized reading' (duration: 220.60207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.328868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.191188ms","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-14T05:01:31.3289Z","caller":"traceutil/trace.go:171","msg":"trace[1271464587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"401.24968ms","start":"2026-02-14T05:01:30.927641Z","end":"2026-02-14T05:01:31.32889Z","steps":["trace[1271464587] 'agreement among raft nodes before linearized reading' (duration: 401.155387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.328931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:30.927626Z","time spent":"401.296751ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T05:01:31.329114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.413304ms","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-14T05:01:31.329175Z","caller":"traceutil/trace.go:171","msg":"trace[2126081003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"439.495927ms","start":"2026-02-14T05:01:30.889669Z","end":"2026-02-14T05:01:31.329165Z","steps":["trace[2126081003] 'agreement among raft nodes before linearized reading' (duration: 439.374494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:31.329208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:30.889659Z","time spent":"439.540238ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T05:01:53.666498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.434805ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:01:53.666643Z","caller":"traceutil/trace.go:171","msg":"trace[722457360] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"989.786182ms","start":"2026-02-14T05:01:52.676846Z","end":"2026-02-14T05:01:53.666632Z","steps":["trace[722457360] 'read index received' (duration: 28.166722ms)","trace[722457360] 'applied index is now lower than readState.Index' (duration: 961.61892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:53.667408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.559502ms","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-14T05:01:53.667425Z","caller":"traceutil/trace.go:171","msg":"trace[629624621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"990.618954ms","start":"2026-02-14T05:01:52.676801Z","end":"2026-02-14T05:01:53.66742Z","steps":["trace[629624621] 'agreement among raft nodes before linearized reading' (duration: 990.541012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:53.667441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:52.676779Z","time spent":"990.658465ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T05:01:53.667713Z","caller":"traceutil/trace.go:171","msg":"trace[1640595531] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"658.074264ms","start":"2026-02-14T05:01:53.009633Z","end":"2026-02-14T05:01:53.667707Z","steps":["trace[1640595531] 'process raft request' (duration: 656.941564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:53.667766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.009619Z","time spent":"658.107685ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:54.010525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.464709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:01:54.010643Z","caller":"traceutil/trace.go:171","msg":"trace[834251439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"575.614303ms","start":"2026-02-14T05:01:53.43499Z","end":"2026-02-14T05:01:54.010605Z","steps":["trace[834251439] 'agreement among raft nodes before linearized reading' (duration: 232.70751ms)","trace[834251439] 'range keys from in-memory index tree' (duration: 342.712688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.010691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.434976Z","time spent":"575.705075ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:01:54.011488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.294817ms","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-14T05:01:54.011521Z","caller":"traceutil/trace.go:171","msg":"trace[1068350002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"503.362039ms","start":"2026-02-14T05:01:53.508144Z","end":"2026-02-14T05:01:54.011506Z","steps":["trace[1068350002] 'agreement among raft nodes before linearized reading' (duration: 159.681416ms)","trace[1068350002] 'range keys from in-memory index tree' (duration: 343.618172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.011542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.508133Z","time spent":"503.40329ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T05:01:54.011681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.032246ms","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-14T05:01:54.01173Z","caller":"traceutil/trace.go:171","msg":"trace[1885250175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"507.097087ms","start":"2026-02-14T05:01:53.504618Z","end":"2026-02-14T05:01:54.011715Z","steps":["trace[1885250175] 'agreement among raft nodes before linearized reading' (duration: 163.211668ms)","trace[1885250175] 'range keys from in-memory index tree' (duration: 343.799267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.011772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.504605Z","time spent":"507.140019ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T05:01:54.014568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.410477ms","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-14T05:01:54.014605Z","caller":"traceutil/trace.go:171","msg":"trace[160783502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"575.454998ms","start":"2026-02-14T05:01:53.439135Z","end":"2026-02-14T05:01:54.01459Z","steps":["trace[160783502] 'agreement among raft nodes before linearized reading' (duration: 228.698544ms)","trace[160783502] 'range keys from in-memory index tree' (duration: 346.679942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.014629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.439128Z","time spent":"575.49368ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-14T05:01:54.020307Z","caller":"traceutil/trace.go:171","msg":"trace[989849190] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"277.758256ms","start":"2026-02-14T05:01:53.742512Z","end":"2026-02-14T05:01:54.02027Z","steps":["trace[989849190] 'read index received' (duration: 239.812497ms)","trace[989849190] 'applied index is now lower than readState.Index' (duration: 37.755594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.021032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.517136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:54.021091Z","caller":"traceutil/trace.go:171","msg":"trace[1463415241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"278.571398ms","start":"2026-02-14T05:01:53.742507Z","end":"2026-02-14T05:01:54.021079Z","steps":["trace[1463415241] 'agreement among raft nodes before linearized reading' (duration: 277.90096ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:54.021992Z","caller":"traceutil/trace.go:171","msg":"trace[1565016536] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"347.941296ms","start":"2026-02-14T05:01:53.674018Z","end":"2026-02-14T05:01:54.02196Z","steps":["trace[1565016536] 'process raft request' (duration: 308.373763ms)","trace[1565016536] 'compare' (duration: 27.489065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:54.022072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:53.674008Z","time spent":"348.026098ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:01:54.17674Z","caller":"traceutil/trace.go:171","msg":"trace[1274420551] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"137.014789ms","start":"2026-02-14T05:01:54.03962Z","end":"2026-02-14T05:01:54.176635Z","steps":["trace[1274420551] 'process raft request' (duration: 136.370292ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:54.181891Z","caller":"traceutil/trace.go:171","msg":"trace[729773806] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"142.202675ms","start":"2026-02-14T05:01:54.039679Z","end":"2026-02-14T05:01:54.181881Z","steps":["trace[729773806] 'process raft request' (duration: 142.041161ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:54.181972Z","caller":"traceutil/trace.go:171","msg":"trace[665548558] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"142.231097ms","start":"2026-02-14T05:01:54.039734Z","end":"2026-02-14T05:01:54.181965Z","steps":["trace[665548558] 'process raft request' (duration: 142.024861ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:54.182023Z","caller":"traceutil/trace.go:171","msg":"trace[1177927253] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"140.891411ms","start":"2026-02-14T05:01:54.041127Z","end":"2026-02-14T05:01:54.182019Z","steps":["trace[1177927253] 'process raft request' (duration: 140.661415ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:02.321248Z","caller":"traceutil/trace.go:171","msg":"trace[1760404636] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"122.948089ms","start":"2026-02-14T05:02:02.198253Z","end":"2026-02-14T05:02:02.321201Z","steps":["trace[1760404636] 'read index received' (duration: 122.776055ms)","trace[1760404636] 'applied index is now lower than readState.Index' (duration: 171.134µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:02.321516Z","caller":"traceutil/trace.go:171","msg":"trace[245991921] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"228.563772ms","start":"2026-02-14T05:02:02.092942Z","end":"2026-02-14T05:02:02.321506Z","steps":["trace[245991921] 'process raft request' (duration: 228.14094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:02.321751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.478383ms","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-14T05:02:02.321782Z","caller":"traceutil/trace.go:171","msg":"trace[1693490468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"123.556896ms","start":"2026-02-14T05:02:02.198215Z","end":"2026-02-14T05:02:02.321772Z","steps":["trace[1693490468] 'agreement among raft nodes before linearized reading' (duration: 123.440902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:02.321944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.683759ms","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-14T05:02:02.321971Z","caller":"traceutil/trace.go:171","msg":"trace[620252542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"112.73197ms","start":"2026-02-14T05:02:02.209231Z","end":"2026-02-14T05:02:02.321963Z","steps":["trace[620252542] 'agreement among raft nodes before linearized reading' (duration: 112.660378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:02.737403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.565536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:02:02.737624Z","caller":"traceutil/trace.go:171","msg":"trace[347488152] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"403.394786ms","start":"2026-02-14T05:02:02.334211Z","end":"2026-02-14T05:02:02.737606Z","steps":["trace[347488152] 'process raft request' (duration: 403.335384ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:02.737684Z","caller":"traceutil/trace.go:171","msg":"trace[748391446] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"403.609791ms","start":"2026-02-14T05:02:02.334048Z","end":"2026-02-14T05:02:02.737658Z","steps":["trace[748391446] 'process raft request' (duration: 170.701317ms)","trace[748391446] 'compare' (duration: 232.470523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:02:02.737723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:02.334208Z","time spent":"403.480168ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:02.73777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:02.334019Z","time spent":"403.701594ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:02:02.737867Z","caller":"traceutil/trace.go:171","msg":"trace[271940253] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"403.625402ms","start":"2026-02-14T05:02:02.334236Z","end":"2026-02-14T05:02:02.737861Z","steps":["trace[271940253] 'process raft request' (duration: 403.341474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:02.737891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:02.334233Z","time spent":"403.644112ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:02:02.737947Z","caller":"traceutil/trace.go:171","msg":"trace[614847208] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"403.747475ms","start":"2026-02-14T05:02:02.334187Z","end":"2026-02-14T05:02:02.737934Z","steps":["trace[614847208] 'process raft request' (duration: 403.299393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:02.738021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:02.334182Z","time spent":"403.790197ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:02.850398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.161133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:02:02.850491Z","caller":"traceutil/trace.go:171","msg":"trace[1232170201] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"100.017114ms","start":"2026-02-14T05:02:02.750455Z","end":"2026-02-14T05:02:02.850472Z","steps":["trace[1232170201] 'read index received' (duration: 23.93µs)","trace[1232170201] 'applied index is now lower than readState.Index' (duration: 99.991894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:02:02.850558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.091147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:02.850582Z","caller":"traceutil/trace.go:171","msg":"trace[789172444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"100.143028ms","start":"2026-02-14T05:02:02.75043Z","end":"2026-02-14T05:02:02.850573Z","steps":["trace[789172444] 'agreement among raft nodes before linearized reading' (duration: 100.086966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:04.976104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194725841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:02:05.476894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194725841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:02:05.967843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.634210717s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T05:02:05.968041Z","caller":"traceutil/trace.go:171","msg":"trace[363809326] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"1.492388451s","start":"2026-02-14T05:02:04.475638Z","end":"2026-02-14T05:02:05.968027Z","steps":["trace[363809326] 'read index received' (duration: 1.492275218s)","trace[363809326] 'applied index is now lower than readState.Index' (duration: 112.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:05.96818Z","caller":"traceutil/trace.go:171","msg":"trace[179615628] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.634600967s","start":"2026-02-14T05:02:04.333573Z","end":"2026-02-14T05:02:05.968174Z","steps":["trace[179615628] 'process raft request' (duration: 1.634379231s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.333563Z","time spent":"1.634630547s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:05.968372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49273099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:02:05.968387Z","caller":"traceutil/trace.go:171","msg":"trace[1681472623] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:904; }","duration":"1.492769941s","start":"2026-02-14T05:02:04.475613Z","end":"2026-02-14T05:02:05.968383Z","steps":["trace[1681472623] 'agreement among raft nodes before linearized reading' (duration: 1.492735321s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.4756Z","time spent":"1.492797912s","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:02:05.968478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225324556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:05.968491Z","caller":"traceutil/trace.go:171","msg":"trace[1030911591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"1.225367567s","start":"2026-02-14T05:02:04.743118Z","end":"2026-02-14T05:02:05.968486Z","steps":["trace[1030911591] 'agreement among raft nodes before linearized reading' (duration: 1.225348967s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.743105Z","time spent":"1.225393409s","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:02:05.968633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221018613s","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-14T05:02:05.968648Z","caller":"traceutil/trace.go:171","msg":"trace[106274877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"1.221040843s","start":"2026-02-14T05:02:04.747602Z","end":"2026-02-14T05:02:05.968643Z","steps":["trace[106274877] 'agreement among raft nodes before linearized reading' (duration: 1.220984992s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.747598Z","time spent":"1.221058264s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T05:02:05.96875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221863045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T05:02:05.968762Z","caller":"traceutil/trace.go:171","msg":"trace[687145491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"1.221884325s","start":"2026-02-14T05:02:04.746875Z","end":"2026-02-14T05:02:05.968759Z","steps":["trace[687145491] 'agreement among raft nodes before linearized reading' (duration: 1.221832804s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.74687Z","time spent":"1.221901036s","remote":"127.0.0.1:46912","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-14T05:02:05.968855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224362081s","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-14T05:02:05.968867Z","caller":"traceutil/trace.go:171","msg":"trace[1721827825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"1.224373861s","start":"2026-02-14T05:02:04.744489Z","end":"2026-02-14T05:02:05.968863Z","steps":["trace[1721827825] 'agreement among raft nodes before linearized reading' (duration: 1.22433974s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.744474Z","time spent":"1.224400732s","remote":"127.0.0.1:46912","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-14T05:02:05.968945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224734811s","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-14T05:02:05.968959Z","caller":"traceutil/trace.go:171","msg":"trace[1483777347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"1.224756432s","start":"2026-02-14T05:02:04.744198Z","end":"2026-02-14T05:02:05.968955Z","steps":["trace[1483777347] 'agreement among raft nodes before linearized reading' (duration: 1.224724301s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:05.968971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:04.744194Z","time spent":"1.224774452s","remote":"127.0.0.1:46912","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-14T05:02:06.146982Z","caller":"traceutil/trace.go:171","msg":"trace[1577570342] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"174.466665ms","start":"2026-02-14T05:02:05.972497Z","end":"2026-02-14T05:02:06.146963Z","steps":["trace[1577570342] 'process raft request' (duration: 140.131101ms)","trace[1577570342] 'compare' (duration: 34.213221ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:06.154563Z","caller":"traceutil/trace.go:171","msg":"trace[102119526] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:941; }","duration":"172.348139ms","start":"2026-02-14T05:02:05.982203Z","end":"2026-02-14T05:02:06.154551Z","steps":["trace[102119526] 'read index received' (duration: 130.438925ms)","trace[102119526] 'applied index is now lower than readState.Index' (duration: 41.908724ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:06.154611Z","caller":"traceutil/trace.go:171","msg":"trace[2078451131] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"180.212507ms","start":"2026-02-14T05:02:05.974373Z","end":"2026-02-14T05:02:06.154586Z","steps":["trace[2078451131] 'process raft request' (duration: 180.088924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:06.154639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.423172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:06.154657Z","caller":"traceutil/trace.go:171","msg":"trace[513632955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"172.466143ms","start":"2026-02-14T05:02:05.982186Z","end":"2026-02-14T05:02:06.154652Z","steps":["trace[513632955] 'agreement among raft nodes before linearized reading' (duration: 172.415421ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:06.154752Z","caller":"traceutil/trace.go:171","msg":"trace[336581258] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"180.34078ms","start":"2026-02-14T05:02:05.974406Z","end":"2026-02-14T05:02:06.154747Z","steps":["trace[336581258] 'process raft request' (duration: 180.109654ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:06.154812Z","caller":"traceutil/trace.go:171","msg":"trace[908237599] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"179.697593ms","start":"2026-02-14T05:02:05.975108Z","end":"2026-02-14T05:02:06.154806Z","steps":["trace[908237599] 'process raft request' (duration: 179.423566ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:20.300077Z","caller":"traceutil/trace.go:171","msg":"trace[513566423] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"183.170174ms","start":"2026-02-14T05:02:20.116889Z","end":"2026-02-14T05:02:20.300059Z","steps":["trace[513566423] 'process raft request' (duration: 183.062391ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:20.40941Z","caller":"traceutil/trace.go:171","msg":"trace[590369940] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"102.925662ms","start":"2026-02-14T05:02:20.30618Z","end":"2026-02-14T05:02:20.409106Z","steps":["trace[590369940] 'process raft request' (duration: 55.227505ms)","trace[590369940] 'compare' (duration: 47.376978ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:20.409013Z","caller":"traceutil/trace.go:171","msg":"trace[1661997959] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"102.164692ms","start":"2026-02-14T05:02:20.306825Z","end":"2026-02-14T05:02:20.408989Z","steps":["trace[1661997959] 'process raft request' (duration: 102.132391ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:20.411569Z","caller":"traceutil/trace.go:171","msg":"trace[1712130224] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"105.242763ms","start":"2026-02-14T05:02:20.306283Z","end":"2026-02-14T05:02:20.411526Z","steps":["trace[1712130224] 'process raft request' (duration: 102.599763ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:20.411674Z","caller":"traceutil/trace.go:171","msg":"trace[129606276] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"105.353885ms","start":"2026-02-14T05:02:20.306313Z","end":"2026-02-14T05:02:20.411667Z","steps":["trace[129606276] 'process raft request' (duration: 102.613713ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:21.263248Z","caller":"traceutil/trace.go:171","msg":"trace[1736425820] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"328.378208ms","start":"2026-02-14T05:02:20.934854Z","end":"2026-02-14T05:02:21.263232Z","steps":["trace[1736425820] 'process raft request' (duration: 328.158542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:21.263349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:20.934843Z","time spent":"328.456881ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:21.984528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536553194726053,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T05:02:22.140452Z","caller":"traceutil/trace.go:171","msg":"trace[86189548] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"656.481035ms","start":"2026-02-14T05:02:21.483793Z","end":"2026-02-14T05:02:22.140274Z","steps":["trace[86189548] 'read index received' (duration: 655.13874ms)","trace[86189548] 'applied index is now lower than readState.Index' (duration: 1.341365ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:22.140701Z","caller":"traceutil/trace.go:171","msg":"trace[871217695] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"872.335931ms","start":"2026-02-14T05:02:21.268356Z","end":"2026-02-14T05:02:22.140692Z","steps":["trace[871217695] 'process raft request' (duration: 870.626286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.141651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:21.268335Z","time spent":"873.235204ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:22.141919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.120568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-14T05:02:22.141983Z","caller":"traceutil/trace.go:171","msg":"trace[1933876705] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1009; }","duration":"658.22052ms","start":"2026-02-14T05:02:21.483753Z","end":"2026-02-14T05:02:22.141973Z","steps":["trace[1933876705] 'agreement among raft nodes before linearized reading' (duration: 658.082637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.142024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:21.483734Z","time spent":"658.282021ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T05:02:22.142239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.981109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-14T05:02:22.14229Z","caller":"traceutil/trace.go:171","msg":"trace[932766903] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1009; }","duration":"651.049941ms","start":"2026-02-14T05:02:21.491232Z","end":"2026-02-14T05:02:22.142282Z","steps":["trace[932766903] 'agreement among raft nodes before linearized reading' (duration: 650.961119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.142327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:21.491224Z","time spent":"651.094362ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T05:02:22.142543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.376445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T05:02:22.142596Z","caller":"traceutil/trace.go:171","msg":"trace[1218438171] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1009; }","duration":"182.465547ms","start":"2026-02-14T05:02:21.960123Z","end":"2026-02-14T05:02:22.142588Z","steps":["trace[1218438171] 'agreement among raft nodes before linearized reading' (duration: 182.394036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.14288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-14T05:02:22.142974Z","caller":"traceutil/trace.go:171","msg":"trace[84932262] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1009; }","duration":"207.508173ms","start":"2026-02-14T05:02:21.935458Z","end":"2026-02-14T05:02:22.142966Z","steps":["trace[84932262] 'agreement among raft nodes before linearized reading' (duration: 207.36947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.143222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.869642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:22.143291Z","caller":"traceutil/trace.go:171","msg":"trace[1551353705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"397.087908ms","start":"2026-02-14T05:02:21.746196Z","end":"2026-02-14T05:02:22.143284Z","steps":["trace[1551353705] 'agreement among raft nodes before linearized reading' (duration: 397.004926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:22.143345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:21.746156Z","time spent":"397.16665ms","remote":"127.0.0.1:46670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:02:22.137721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.696941ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:22.146759Z","caller":"traceutil/trace.go:171","msg":"trace[798377892] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1008; }","duration":"380.711438ms","start":"2026-02-14T05:02:21.765997Z","end":"2026-02-14T05:02:22.146709Z","steps":["trace[798377892] 'range keys from in-memory index tree' (duration: 371.681961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:05:39.407908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.503154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:05:39.407984Z","caller":"traceutil/trace.go:171","msg":"trace[854168160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1946; }","duration":"203.611767ms","start":"2026-02-14T05:05:39.204358Z","end":"2026-02-14T05:05:39.40797Z","steps":["trace[854168160] 'range keys from in-memory index tree' (duration: 203.33975ms)"],"step_count":1}