{"level":"warn","ts":"2026-02-24T12:55:40.138771Z","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-24T12:55:40.138893Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-24T12:55:40.138976Z","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-24T12:55:40.138991Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-02-24T12:55:40.139016Z","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-24T12:55:40.139881Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-02-24T12:55:40.139966Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-24T12:55:40.308133Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"167.910887ms"} {"level":"info","ts":"2026-02-24T12:55:40.329649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-02-24T12:55:40.330085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T12:55:40.330241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-02-24T12:55:40.330292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T12:55:40.330324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-02-24T12:55:40.330523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-02-24T12:55:40.33941Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T12:55:40.341068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T12:55:40.342241Z","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-24T12:55:40.344496Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T12:55:40.34499Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T12:55:40.345098Z","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-24T12:55:40.34528Z","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-24T12:55:40.345299Z","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-24T12:55:40.346311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-02-24T12:55:40.347023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-02-24T12:55:40.348761Z","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-24T12:55:40.348903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-02-24T12:55:40.348967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-02-24T12:55:40.349404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T12:55:40.349469Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T12:55:41.33116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T12:55:41.331225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T12:55:41.331285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-02-24T12:55:41.331305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-02-24T12:55:41.331316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-02-24T12:55:41.331334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-02-24T12:55:41.331348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-02-24T12:55:41.332344Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:41.333166Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T12:55:41.333184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:55:41.333253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:55:41.333614Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:41.333738Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:41.333775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:41.333877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T12:55:41.33392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T12:55:41.337765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T12:55:41.338157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"warn","ts":"2026-02-24T12:55:51.1443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.586541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:55:51.144446Z","caller":"traceutil/trace.go:171","msg":"trace[1970401569] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"449.521042ms","start":"2026-02-24T12:55:50.694905Z","end":"2026-02-24T12:55:51.144426Z","steps":["trace[1970401569] 'read index received' (duration: 268.171183ms)","trace[1970401569] 'applied index is now lower than readState.Index' (duration: 181.348338ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:51.14456Z","caller":"traceutil/trace.go:171","msg":"trace[1309811383] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"475.474757ms","start":"2026-02-24T12:55:50.669056Z","end":"2026-02-24T12:55:51.14453Z","steps":["trace[1309811383] 'process raft request' (duration: 294.091165ms)","trace[1309811383] 'compare' (duration: 180.40139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:51.144656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.745417ms","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-24T12:55:51.14471Z","caller":"traceutil/trace.go:171","msg":"trace[1102588381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:328; }","duration":"449.86027ms","start":"2026-02-24T12:55:50.694836Z","end":"2026-02-24T12:55:51.144696Z","steps":["trace[1102588381] 'agreement among raft nodes before linearized reading' (duration: 449.714424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:51.144706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:50.66902Z","time spent":"475.637997ms","remote":"127.0.0.1:44152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T12:55:51.14483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:50.694807Z","time spent":"450.006847ms","remote":"127.0.0.1:44206","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-24T12:55:51.144903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.988078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T12:55:51.145019Z","caller":"traceutil/trace.go:171","msg":"trace[244344115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:328; }","duration":"239.177879ms","start":"2026-02-24T12:55:50.905814Z","end":"2026-02-24T12:55:51.144992Z","steps":["trace[244344115] 'agreement among raft nodes before linearized reading' (duration: 238.953444ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:51.541347Z","caller":"traceutil/trace.go:171","msg":"trace[786917825] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:368; }","duration":"108.251925ms","start":"2026-02-24T12:55:51.433049Z","end":"2026-02-24T12:55:51.541301Z","steps":["trace[786917825] 'read index received' (duration: 2.913084ms)","trace[786917825] 'applied index is now lower than readState.Index' (duration: 105.334841ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:51.541469Z","caller":"traceutil/trace.go:171","msg":"trace[1018612304] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"109.305586ms","start":"2026-02-24T12:55:51.432071Z","end":"2026-02-24T12:55:51.541376Z","steps":["trace[1018612304] 'process raft request' (duration: 56.181013ms)","trace[1018612304] 'compare' (duration: 52.895407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:51.541536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.46496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-24T12:55:51.54208Z","caller":"traceutil/trace.go:171","msg":"trace[508881463] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:359; }","duration":"109.059638ms","start":"2026-02-24T12:55:51.433007Z","end":"2026-02-24T12:55:51.542066Z","steps":["trace[508881463] 'agreement among raft nodes before linearized reading' (duration: 108.422855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:59.777688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.876634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:55:59.778067Z","caller":"traceutil/trace.go:171","msg":"trace[1655539580] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"492.307487ms","start":"2026-02-24T12:55:59.285733Z","end":"2026-02-24T12:55:59.778041Z","steps":["trace[1655539580] 'process raft request' (duration: 97.850929ms)","trace[1655539580] 'compare' (duration: 393.750108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:59.778172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:59.285714Z","time spent":"492.409417ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:56:07.586594Z","caller":"traceutil/trace.go:171","msg":"trace[985484449] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"272.729111ms","start":"2026-02-24T12:56:07.313837Z","end":"2026-02-24T12:56:07.586566Z","steps":["trace[985484449] 'process raft request' (duration: 272.523722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:08.027952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.896012ms","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-24T12:56:08.028078Z","caller":"traceutil/trace.go:171","msg":"trace[1735598814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:441; }","duration":"217.046859ms","start":"2026-02-24T12:56:07.81101Z","end":"2026-02-24T12:56:08.028057Z","steps":["trace[1735598814] 'range keys from in-memory index tree' (duration: 216.721449ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:08.18252Z","caller":"traceutil/trace.go:171","msg":"trace[1280656918] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"146.637639ms","start":"2026-02-24T12:56:08.035854Z","end":"2026-02-24T12:56:08.182491Z","steps":["trace[1280656918] 'process raft request' (duration: 146.474304ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:09.977761Z","caller":"traceutil/trace.go:171","msg":"trace[302722401] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"381.497496ms","start":"2026-02-24T12:56:09.596235Z","end":"2026-02-24T12:56:09.977733Z","steps":["trace[302722401] 'process raft request' (duration: 381.333531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:09.977932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:09.596201Z","time spent":"381.636916ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:56:10.085878Z","caller":"traceutil/trace.go:171","msg":"trace[1564979858] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:457; }","duration":"184.341847ms","start":"2026-02-24T12:56:09.901502Z","end":"2026-02-24T12:56:10.085843Z","steps":["trace[1564979858] 'read index received' (duration: 76.129229ms)","trace[1564979858] 'applied index is now lower than readState.Index' (duration: 108.210748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:56:10.086076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.551017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:10.086118Z","caller":"traceutil/trace.go:171","msg":"trace[732594282] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:443; }","duration":"184.657501ms","start":"2026-02-24T12:56:09.901449Z","end":"2026-02-24T12:56:10.086107Z","steps":["trace[732594282] 'agreement among raft nodes before linearized reading' (duration: 184.563415ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:16.125142Z","caller":"traceutil/trace.go:171","msg":"trace[156481093] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"118.419495ms","start":"2026-02-24T12:56:16.006701Z","end":"2026-02-24T12:56:16.125121Z","steps":["trace[156481093] 'process raft request' (duration: 117.854415ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:48.402874Z","caller":"traceutil/trace.go:171","msg":"trace[31378767] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"214.584054ms","start":"2026-02-24T12:56:48.188266Z","end":"2026-02-24T12:56:48.40285Z","steps":["trace[31378767] 'process raft request' (duration: 214.411001ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:48.428474Z","caller":"traceutil/trace.go:171","msg":"trace[1017156322] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"237.624636ms","start":"2026-02-24T12:56:48.190818Z","end":"2026-02-24T12:56:48.428443Z","steps":["trace[1017156322] 'process raft request' (duration: 237.444984ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:48.812035Z","caller":"traceutil/trace.go:171","msg":"trace[250119285] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"350.426123ms","start":"2026-02-24T12:56:48.461579Z","end":"2026-02-24T12:56:48.812005Z","steps":["trace[250119285] 'read index received' (duration: 350.319487ms)","trace[250119285] 'applied index is now lower than readState.Index' (duration: 105.646µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:56:48.812083Z","caller":"traceutil/trace.go:171","msg":"trace[1233670520] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"376.517091ms","start":"2026-02-24T12:56:48.435545Z","end":"2026-02-24T12:56:48.812062Z","steps":["trace[1233670520] 'process raft request' (duration: 376.353978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:48.812216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:48.435531Z","time spent":"376.607428ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:56:48.812218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.643243ms","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-24T12:56:48.812278Z","caller":"traceutil/trace.go:171","msg":"trace[373454857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"350.741869ms","start":"2026-02-24T12:56:48.461528Z","end":"2026-02-24T12:56:48.81227Z","steps":["trace[373454857] 'agreement among raft nodes before linearized reading' (duration: 350.601385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:48.812312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:48.461505Z","time spent":"350.788707ms","remote":"127.0.0.1:44206","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-24T12:56:48.812655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.971118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:48.812727Z","caller":"traceutil/trace.go:171","msg":"trace[1444995527] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:572; }","duration":"191.082653ms","start":"2026-02-24T12:56:48.621638Z","end":"2026-02-24T12:56:48.812721Z","steps":["trace[1444995527] 'agreement among raft nodes before linearized reading' (duration: 190.997027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:48.812869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.322013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:48.812889Z","caller":"traceutil/trace.go:171","msg":"trace[1361138604] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:572; }","duration":"223.3767ms","start":"2026-02-24T12:56:48.589507Z","end":"2026-02-24T12:56:48.812883Z","steps":["trace[1361138604] 'agreement among raft nodes before linearized reading' (duration: 223.111512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:50.906222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330221998295625494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T12:56:51.407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330221998295625494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T12:56:51.407628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.983114276s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T12:56:51.725171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.302053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T12:56:51.725317Z","caller":"traceutil/trace.go:171","msg":"trace[39259982] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"1.319618427s","start":"2026-02-24T12:56:50.405657Z","end":"2026-02-24T12:56:51.725275Z","steps":["trace[39259982] 'read index received' (duration: 1.002106922s)","trace[39259982] 'applied index is now lower than readState.Index' (duration: 317.509165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:56:51.725471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31980927s","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-24T12:56:51.725522Z","caller":"traceutil/trace.go:171","msg":"trace[27578622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.319893476s","start":"2026-02-24T12:56:50.405612Z","end":"2026-02-24T12:56:51.725505Z","steps":["trace[27578622] 'agreement among raft nodes before linearized reading' (duration: 1.319752471s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.725547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:50.405596Z","time spent":"1.319945604s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T12:56:51.725571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294065987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-24T12:56:51.725563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.136603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:51.725617Z","caller":"traceutil/trace.go:171","msg":"trace[2102950977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.294151394s","start":"2026-02-24T12:56:50.431451Z","end":"2026-02-24T12:56:51.725602Z","steps":["trace[2102950977] 'agreement among raft nodes before linearized reading' (duration: 1.293971551s)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:51.725652Z","caller":"traceutil/trace.go:171","msg":"trace[1299112449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"691.286708ms","start":"2026-02-24T12:56:51.034342Z","end":"2026-02-24T12:56:51.725629Z","steps":["trace[1299112449] 'agreement among raft nodes before linearized reading' (duration: 691.114474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.725673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:50.43143Z","time spent":"1.294225723s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T12:56:51.725696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:51.034322Z","time spent":"691.363835ms","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:56:51.725759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.514913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:51.72578Z","caller":"traceutil/trace.go:171","msg":"trace[658114111] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:573; }","duration":"625.567901ms","start":"2026-02-24T12:56:51.100207Z","end":"2026-02-24T12:56:51.725775Z","steps":["trace[658114111] 'agreement among raft nodes before linearized reading' (duration: 625.523263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.725804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:51.100193Z","time spent":"625.60247ms","remote":"127.0.0.1:44074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-24T12:56:51.725937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.75253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T12:56:51.725966Z","caller":"traceutil/trace.go:171","msg":"trace[709187361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"909.820257ms","start":"2026-02-24T12:56:50.816141Z","end":"2026-02-24T12:56:51.725962Z","steps":["trace[709187361] 'agreement among raft nodes before linearized reading' (duration: 909.763419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.72598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:50.816122Z","time spent":"909.854936ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T12:56:51.725993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.578181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:51.726042Z","caller":"traceutil/trace.go:171","msg":"trace[187797413] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:573; }","duration":"991.677108ms","start":"2026-02-24T12:56:50.734351Z","end":"2026-02-24T12:56:51.726028Z","steps":["trace[187797413] 'agreement among raft nodes before linearized reading' (duration: 991.594451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.726083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:50.734327Z","time spent":"991.743275ms","remote":"127.0.0.1:44080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-24T12:56:51.72609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.704409ms","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-24T12:56:51.726202Z","caller":"traceutil/trace.go:171","msg":"trace[734761066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"856.799946ms","start":"2026-02-24T12:56:50.869336Z","end":"2026-02-24T12:56:51.726136Z","steps":["trace[734761066] 'agreement among raft nodes before linearized reading' (duration: 856.627842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:51.726248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:56:50.869317Z","time spent":"856.919021ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T12:56:51.952811Z","caller":"traceutil/trace.go:171","msg":"trace[926728091] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"222.780544ms","start":"2026-02-24T12:56:51.729985Z","end":"2026-02-24T12:56:51.952765Z","steps":["trace[926728091] 'process raft request' (duration: 222.492564ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:52.013765Z","caller":"traceutil/trace.go:171","msg":"trace[95089681] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"283.129475ms","start":"2026-02-24T12:56:51.730619Z","end":"2026-02-24T12:56:52.013748Z","steps":["trace[95089681] 'process raft request' (duration: 283.091006ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:52.013988Z","caller":"traceutil/trace.go:171","msg":"trace[634703186] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"282.600234ms","start":"2026-02-24T12:56:51.731342Z","end":"2026-02-24T12:56:52.013943Z","steps":["trace[634703186] 'process raft request' (duration: 282.384482ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:52.01475Z","caller":"traceutil/trace.go:171","msg":"trace[181193743] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"283.660144ms","start":"2026-02-24T12:56:51.730163Z","end":"2026-02-24T12:56:52.013823Z","steps":["trace[181193743] 'process raft request' (duration: 283.450232ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:52.6299Z","caller":"traceutil/trace.go:171","msg":"trace[1592337235] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"223.767905ms","start":"2026-02-24T12:56:52.406022Z","end":"2026-02-24T12:56:52.62979Z","steps":["trace[1592337235] 'process raft request' (duration: 219.572488ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:54.632368Z","caller":"traceutil/trace.go:171","msg":"trace[1142984543] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"150.637453ms","start":"2026-02-24T12:56:54.481709Z","end":"2026-02-24T12:56:54.632346Z","steps":["trace[1142984543] 'process raft request' (duration: 150.533926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:56:55.290162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.071739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:56:55.290236Z","caller":"traceutil/trace.go:171","msg":"trace[1563962624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"257.182577ms","start":"2026-02-24T12:56:55.033036Z","end":"2026-02-24T12:56:55.290219Z","steps":["trace[1563962624] 'range keys from in-memory index tree' (duration: 257.004572ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:00.840966Z","caller":"traceutil/trace.go:171","msg":"trace[22755015] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"117.976216ms","start":"2026-02-24T12:57:00.722966Z","end":"2026-02-24T12:57:00.840943Z","steps":["trace[22755015] 'read index received' (duration: 117.76758ms)","trace[22755015] 'applied index is now lower than readState.Index' (duration: 207.426µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:57:00.841008Z","caller":"traceutil/trace.go:171","msg":"trace[1434354497] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"205.552461ms","start":"2026-02-24T12:57:00.635423Z","end":"2026-02-24T12:57:00.840975Z","steps":["trace[1434354497] 'process raft request' (duration: 205.369764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:00.841354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.366457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-02-24T12:57:00.841402Z","caller":"traceutil/trace.go:171","msg":"trace[155987038] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:688; }","duration":"118.470635ms","start":"2026-02-24T12:57:00.722918Z","end":"2026-02-24T12:57:00.841389Z","steps":["trace[155987038] 'agreement among raft nodes before linearized reading' (duration: 118.128823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:07.006831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.097991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:57:07.006933Z","caller":"traceutil/trace.go:171","msg":"trace[1636141801] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:761; }","duration":"145.25408ms","start":"2026-02-24T12:57:06.86166Z","end":"2026-02-24T12:57:07.006914Z","steps":["trace[1636141801] 'count revisions from in-memory index tree' (duration: 144.952744ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:40.645788Z","caller":"traceutil/trace.go:171","msg":"trace[1293543629] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"200.890897ms","start":"2026-02-24T12:57:40.444874Z","end":"2026-02-24T12:57:40.645765Z","steps":["trace[1293543629] 'read index received' (duration: 184.282617ms)","trace[1293543629] 'applied index is now lower than readState.Index' (duration: 16.60732ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:57:40.645891Z","caller":"traceutil/trace.go:171","msg":"trace[689569709] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"260.441875ms","start":"2026-02-24T12:57:40.385414Z","end":"2026-02-24T12:57:40.645856Z","steps":["trace[689569709] 'process raft request' (duration: 243.747463ms)","trace[689569709] 'compare' (duration: 16.518948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:57:40.646035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.139333ms","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-24T12:57:40.646118Z","caller":"traceutil/trace.go:171","msg":"trace[2098422553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"201.258715ms","start":"2026-02-24T12:57:40.444838Z","end":"2026-02-24T12:57:40.646097Z","steps":["trace[2098422553] 'agreement among raft nodes before linearized reading' (duration: 201.05412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:50.074968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.070376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T12:57:50.075073Z","caller":"traceutil/trace.go:171","msg":"trace[1154162108] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"155.700953ms","start":"2026-02-24T12:57:49.919355Z","end":"2026-02-24T12:57:50.075056Z","steps":["trace[1154162108] 'read index received' (duration: 76.472µs)","trace[1154162108] 'applied index is now lower than readState.Index' (duration: 155.622751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:57:50.075264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.91121ms","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-24T12:57:50.075291Z","caller":"traceutil/trace.go:171","msg":"trace[787181749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"155.988422ms","start":"2026-02-24T12:57:49.919293Z","end":"2026-02-24T12:57:50.075281Z","steps":["trace[787181749] 'agreement among raft nodes before linearized reading' (duration: 155.856148ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:54.264903Z","caller":"traceutil/trace.go:171","msg":"trace[2145788164] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"112.265088ms","start":"2026-02-24T12:57:54.152616Z","end":"2026-02-24T12:57:54.264881Z","steps":["trace[2145788164] 'process raft request' (duration: 112.152474ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:54.922585Z","caller":"traceutil/trace.go:171","msg":"trace[325594510] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"147.602497ms","start":"2026-02-24T12:57:54.774953Z","end":"2026-02-24T12:57:54.922556Z","steps":["trace[325594510] 'process raft request' (duration: 122.23079ms)","trace[325594510] 'compare' (duration: 25.239212ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:57:54.926715Z","caller":"traceutil/trace.go:171","msg":"trace[201694461] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"151.489064ms","start":"2026-02-24T12:57:54.775203Z","end":"2026-02-24T12:57:54.926692Z","steps":["trace[201694461] 'process raft request' (duration: 151.415281ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:56.700425Z","caller":"traceutil/trace.go:171","msg":"trace[944608326] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"189.70996ms","start":"2026-02-24T12:57:56.510689Z","end":"2026-02-24T12:57:56.700399Z","steps":["trace[944608326] 'process raft request' (duration: 189.468391ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:58:14.538983Z","caller":"traceutil/trace.go:171","msg":"trace[1691866598] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"114.362908ms","start":"2026-02-24T12:58:14.424601Z","end":"2026-02-24T12:58:14.538963Z","steps":["trace[1691866598] 'read index received' (duration: 114.19715ms)","trace[1691866598] 'applied index is now lower than readState.Index' (duration: 164.638µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:58:14.539108Z","caller":"traceutil/trace.go:171","msg":"trace[1612045087] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"119.71916ms","start":"2026-02-24T12:58:14.419363Z","end":"2026-02-24T12:58:14.539083Z","steps":["trace[1612045087] 'process raft request' (duration: 119.457607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:58:14.539204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.572319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:58:14.539319Z","caller":"traceutil/trace.go:171","msg":"trace[1853693853] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1002; }","duration":"114.712425ms","start":"2026-02-24T12:58:14.424582Z","end":"2026-02-24T12:58:14.539294Z","steps":["trace[1853693853] 'agreement among raft nodes before linearized reading' (duration: 114.549528ms)"],"step_count":1}