{"level":"warn","ts":"2026-02-19T02:56:29.654275Z","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-19T02:56:29.654402Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100: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.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-19T02:56:29.654522Z","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-19T02:56:29.654534Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-02-19T02:56:29.654557Z","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-19T02:56:29.655707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-02-19T02:56:29.655922Z","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.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-19T02:56:29.660512Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.112397ms"} {"level":"info","ts":"2026-02-19T02:56:29.669858Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-02-19T02:56:29.669967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:56:29.671048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-02-19T02:56:29.671088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:56:29.671099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-02-19T02:56:29.671173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-02-19T02:56:29.679273Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:56:29.681211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:56:29.68276Z","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-19T02:56:29.685781Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:56:29.686004Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:56:29.6864Z","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-19T02:56:29.686492Z","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-19T02:56:29.686503Z","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-19T02:56:29.686977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-02-19T02:56:29.687164Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-02-19T02:56:29.691216Z","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-19T02:56:29.691367Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-02-19T02:56:29.691416Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-02-19T02:56:29.691518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:56:29.691564Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:56:30.272106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:56:30.272164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:56:30.272216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-02-19T02:56:30.272241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:56:30.272258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-02-19T02:56:30.272278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-02-19T02:56:30.272296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-02-19T02:56:30.274431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:56:30.274794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:56:30.275243Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:56:30.275609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:56:30.276998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:56:30.277075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:56:30.277098Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:56:30.277135Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:56:30.277146Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:56:30.280116Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:56:30.285126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"info","ts":"2026-02-19T02:56:55.716562Z","caller":"traceutil/trace.go:171","msg":"trace[1297577045] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"227.883087ms","start":"2026-02-19T02:56:55.488649Z","end":"2026-02-19T02:56:55.716532Z","steps":["trace[1297577045] 'read index received' (duration: 227.712873ms)","trace[1297577045] 'applied index is now lower than readState.Index' (duration: 169.334µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:56:55.71661Z","caller":"traceutil/trace.go:171","msg":"trace[2120960292] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"493.1248ms","start":"2026-02-19T02:56:55.223448Z","end":"2026-02-19T02:56:55.716573Z","steps":["trace[2120960292] 'process raft request' (duration: 492.961556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:55.716719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.058032ms","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-19T02:56:55.716787Z","caller":"traceutil/trace.go:171","msg":"trace[519142620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"228.192185ms","start":"2026-02-19T02:56:55.488584Z","end":"2026-02-19T02:56:55.716776Z","steps":["trace[519142620] 'agreement among raft nodes before linearized reading' (duration: 228.069572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:55.71778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:55.223414Z","time spent":"493.296955ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:56:56.256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276896506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:56:56.434366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.475574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:56:56.434599Z","caller":"traceutil/trace.go:171","msg":"trace[1938573241] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"679.370183ms","start":"2026-02-19T02:56:55.755178Z","end":"2026-02-19T02:56:56.434548Z","steps":["trace[1938573241] 'read index received' (duration: 575.232564ms)","trace[1938573241] 'applied index is now lower than readState.Index' (duration: 104.097209ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:56:56.43474Z","caller":"traceutil/trace.go:171","msg":"trace[1480605594] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"714.724061ms","start":"2026-02-19T02:56:55.719991Z","end":"2026-02-19T02:56:56.434715Z","steps":["trace[1480605594] 'process raft request' (duration: 610.467169ms)","trace[1480605594] 'compare' (duration: 100.335381ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:56:56.43483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:55.719972Z","time spent":"714.801204ms","remote":"127.0.0.1:38250","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-19T02:56:56.435067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.229909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-19T02:56:56.435132Z","caller":"traceutil/trace.go:171","msg":"trace[605043422] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:462; }","duration":"661.352712ms","start":"2026-02-19T02:56:55.773761Z","end":"2026-02-19T02:56:56.435114Z","steps":["trace[605043422] 'agreement among raft nodes before linearized reading' (duration: 661.199238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:56.435182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:55.773744Z","time spent":"661.425063ms","remote":"127.0.0.1:38094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-19T02:56:56.435184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.001309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-19T02:56:56.435202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.710243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:56:56.435287Z","caller":"traceutil/trace.go:171","msg":"trace[1508448796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"551.822366ms","start":"2026-02-19T02:56:55.883434Z","end":"2026-02-19T02:56:56.435257Z","steps":["trace[1508448796] 'agreement among raft nodes before linearized reading' (duration: 551.56689ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:56.435282Z","caller":"traceutil/trace.go:171","msg":"trace[231705819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"680.135772ms","start":"2026-02-19T02:56:55.755109Z","end":"2026-02-19T02:56:56.435245Z","steps":["trace[231705819] 'agreement among raft nodes before linearized reading' (duration: 679.865415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:56.43532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:55.88342Z","time spent":"551.892037ms","remote":"127.0.0.1:38002","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-19T02:56:56.435327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:55.755083Z","time spent":"680.232554ms","remote":"127.0.0.1:38250","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-19T02:56:58.543008Z","caller":"traceutil/trace.go:171","msg":"trace[1246110333] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"101.242331ms","start":"2026-02-19T02:56:58.441733Z","end":"2026-02-19T02:56:58.542975Z","steps":["trace[1246110333] 'process raft request' (duration: 101.051157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:59.520652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.898469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:56:59.520773Z","caller":"traceutil/trace.go:171","msg":"trace[928954208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"637.068922ms","start":"2026-02-19T02:56:58.883686Z","end":"2026-02-19T02:56:59.520755Z","steps":["trace[928954208] 'range keys from in-memory index tree' (duration: 636.827007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:59.520842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:58.883671Z","time spent":"637.143224ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:57:06.180699Z","caller":"traceutil/trace.go:171","msg":"trace[308799144] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"133.637741ms","start":"2026-02-19T02:57:06.047038Z","end":"2026-02-19T02:57:06.180676Z","steps":["trace[308799144] 'process raft request' (duration: 133.491567ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:06.748743Z","caller":"traceutil/trace.go:171","msg":"trace[127468775] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"172.977922ms","start":"2026-02-19T02:57:06.575742Z","end":"2026-02-19T02:57:06.74872Z","steps":["trace[127468775] 'process raft request' (duration: 172.836028ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:06.913971Z","caller":"traceutil/trace.go:171","msg":"trace[1557572413] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"317.444226ms","start":"2026-02-19T02:57:06.596504Z","end":"2026-02-19T02:57:06.913948Z","steps":["trace[1557572413] 'read index received' (duration: 152.096233ms)","trace[1557572413] 'applied index is now lower than readState.Index' (duration: 165.346973ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:57:06.914028Z","caller":"traceutil/trace.go:171","msg":"trace[1688694876] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"337.400443ms","start":"2026-02-19T02:57:06.576606Z","end":"2026-02-19T02:57:06.914007Z","steps":["trace[1688694876] 'process raft request' (duration: 337.212579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:06.914143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:06.576595Z","time spent":"337.483535ms","remote":"127.0.0.1:38250","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-19T02:57:06.914168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.796542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T02:57:06.914206Z","caller":"traceutil/trace.go:171","msg":"trace[1856259984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"153.890385ms","start":"2026-02-19T02:57:06.760302Z","end":"2026-02-19T02:57:06.914193Z","steps":["trace[1856259984] 'agreement among raft nodes before linearized reading' (duration: 153.802713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:06.914233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.700802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T02:57:06.91436Z","caller":"traceutil/trace.go:171","msg":"trace[154055877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"317.870806ms","start":"2026-02-19T02:57:06.596469Z","end":"2026-02-19T02:57:06.91434Z","steps":["trace[154055877] 'agreement among raft nodes before linearized reading' (duration: 317.640611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:06.914508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:06.596457Z","time spent":"318.023609ms","remote":"127.0.0.1:38250","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-19T02:57:06.91454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.025817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8fw85\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-19T02:57:06.914589Z","caller":"traceutil/trace.go:171","msg":"trace[1591760738] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8fw85; range_end:; response_count:1; response_revision:525; }","duration":"139.108319ms","start":"2026-02-19T02:57:06.775464Z","end":"2026-02-19T02:57:06.914573Z","steps":["trace[1591760738] 'agreement among raft nodes before linearized reading' (duration: 138.688299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:07.568568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.002374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:57:07.568968Z","caller":"traceutil/trace.go:171","msg":"trace[1472746324] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"649.977664ms","start":"2026-02-19T02:57:06.918969Z","end":"2026-02-19T02:57:07.568946Z","steps":["trace[1472746324] 'process raft request' (duration: 379.317255ms)","trace[1472746324] 'compare' (duration: 269.809259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:07.569115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:06.918953Z","time spent":"650.121208ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:57:07.659146Z","caller":"traceutil/trace.go:171","msg":"trace[1435065707] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"739.769989ms","start":"2026-02-19T02:57:06.919345Z","end":"2026-02-19T02:57:07.659115Z","steps":["trace[1435065707] 'process raft request' (duration: 739.585464ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:07.659407Z","caller":"traceutil/trace.go:171","msg":"trace[1670040622] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"729.778204ms","start":"2026-02-19T02:57:06.929601Z","end":"2026-02-19T02:57:07.65938Z","steps":["trace[1670040622] 'process raft request' (duration: 729.457696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:07.659432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:06.919325Z","time spent":"740.024044ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:07.659539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:06.929588Z","time spent":"729.878567ms","remote":"127.0.0.1:38160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:09.254486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276896639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:57:09.565474Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342594809s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T02:57:09.605672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:08.842198Z","time spent":"763.440152ms","remote":"127.0.0.1:60978","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-19T02:57:09.662949Z","caller":"traceutil/trace.go:171","msg":"trace[161284867] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"909.220017ms","start":"2026-02-19T02:57:08.753701Z","end":"2026-02-19T02:57:09.662921Z","steps":["trace[161284867] 'read index received' (duration: 811.923418ms)","trace[161284867] 'applied index is now lower than readState.Index' (duration: 97.295099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:09.663162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.553314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:09.663215Z","caller":"traceutil/trace.go:171","msg":"trace[665997459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"780.654945ms","start":"2026-02-19T02:57:08.882546Z","end":"2026-02-19T02:57:09.6632Z","steps":["trace[665997459] 'agreement among raft nodes before linearized reading' (duration: 780.535433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:09.663205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.486224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-19T02:57:09.66327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:08.882531Z","time spent":"780.727938ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:57:09.663317Z","caller":"traceutil/trace.go:171","msg":"trace[363009765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"909.641918ms","start":"2026-02-19T02:57:08.753649Z","end":"2026-02-19T02:57:09.66329Z","steps":["trace[363009765] 'agreement among raft nodes before linearized reading' (duration: 909.360431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:09.663382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:08.75363Z","time spent":"909.738109ms","remote":"127.0.0.1:38250","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-19T02:57:09.663408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.770365ms","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-19T02:57:09.663554Z","caller":"traceutil/trace.go:171","msg":"trace[1933313743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"744.880438ms","start":"2026-02-19T02:57:08.918586Z","end":"2026-02-19T02:57:09.663467Z","steps":["trace[1933313743] 'agreement among raft nodes before linearized reading' (duration: 744.664243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:09.663801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:08.918567Z","time spent":"745.215306ms","remote":"127.0.0.1:38250","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-19T02:57:21.740566Z","caller":"traceutil/trace.go:171","msg":"trace[1900667603] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"139.543499ms","start":"2026-02-19T02:57:21.601001Z","end":"2026-02-19T02:57:21.740545Z","steps":["trace[1900667603] 'process raft request' (duration: 139.394315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:28.279632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276896978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:57:28.41012Z","caller":"traceutil/trace.go:171","msg":"trace[1433844080] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"641.59465ms","start":"2026-02-19T02:57:27.768503Z","end":"2026-02-19T02:57:28.410098Z","steps":["trace[1433844080] 'process raft request' (duration: 641.477247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:28.41024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.768486Z","time spent":"641.693013ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:29.50931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.712922ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:57:29.509424Z","caller":"traceutil/trace.go:171","msg":"trace[1261183060] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"1.730516491s","start":"2026-02-19T02:57:27.778887Z","end":"2026-02-19T02:57:29.509403Z","steps":["trace[1261183060] 'read index received' (duration: 631.230197ms)","trace[1261183060] 'applied index is now lower than readState.Index' (duration: 1.099284854s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:29.509867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.730970903s","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-19T02:57:29.509922Z","caller":"traceutil/trace.go:171","msg":"trace[1564702491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"1.731071985s","start":"2026-02-19T02:57:27.778834Z","end":"2026-02-19T02:57:29.509906Z","steps":["trace[1564702491] 'agreement among raft nodes before linearized reading' (duration: 1.73088236s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.509964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.778815Z","time spent":"1.731138178s","remote":"127.0.0.1:38250","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-19T02:57:29.51Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624456339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:29.510032Z","caller":"traceutil/trace.go:171","msg":"trace[956642895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"1.62451137s","start":"2026-02-19T02:57:27.885509Z","end":"2026-02-19T02:57:29.51002Z","steps":["trace[956642895] 'agreement among raft nodes before linearized reading' (duration: 1.62447738s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.510059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.885483Z","time spent":"1.624570872s","remote":"127.0.0.1:38002","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-19T02:57:29.509952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725237741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-19T02:57:29.510208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721949875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T02:57:29.510227Z","caller":"traceutil/trace.go:171","msg":"trace[760173282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"1.722039168s","start":"2026-02-19T02:57:27.788181Z","end":"2026-02-19T02:57:29.510221Z","steps":["trace[760173282] 'agreement among raft nodes before linearized reading' (duration: 1.721988396s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:29.510221Z","caller":"traceutil/trace.go:171","msg":"trace[1958353606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"1.72556737s","start":"2026-02-19T02:57:27.784638Z","end":"2026-02-19T02:57:29.510205Z","steps":["trace[1958353606] 'agreement among raft nodes before linearized reading' (duration: 1.72518753s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.510249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.788163Z","time spent":"1.722081448s","remote":"127.0.0.1:38250","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-19T02:57:29.510274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.784618Z","time spent":"1.725641122s","remote":"127.0.0.1:38250","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-19T02:57:29.510319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724358108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T02:57:29.510334Z","caller":"traceutil/trace.go:171","msg":"trace[2128612599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"1.72441013s","start":"2026-02-19T02:57:27.785919Z","end":"2026-02-19T02:57:29.510329Z","steps":["trace[2128612599] 'agreement among raft nodes before linearized reading' (duration: 1.724368599s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.510351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:27.7859Z","time spent":"1.724446721s","remote":"127.0.0.1:38250","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-19T02:57:29.926383Z","caller":"traceutil/trace.go:171","msg":"trace[1142345266] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"411.970088ms","start":"2026-02-19T02:57:29.514383Z","end":"2026-02-19T02:57:29.926353Z","steps":["trace[1142345266] 'process raft request' (duration: 411.799123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.926535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:29.514366Z","time spent":"412.093601ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:57:29.976938Z","caller":"traceutil/trace.go:171","msg":"trace[1084703616] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"461.519602ms","start":"2026-02-19T02:57:29.515395Z","end":"2026-02-19T02:57:29.976915Z","steps":["trace[1084703616] 'process raft request' (duration: 461.357018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.97707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:29.515377Z","time spent":"461.626355ms","remote":"127.0.0.1:38250","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-19T02:57:29.977416Z","caller":"traceutil/trace.go:171","msg":"trace[1719289954] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"461.896442ms","start":"2026-02-19T02:57:29.515487Z","end":"2026-02-19T02:57:29.977383Z","steps":["trace[1719289954] 'process raft request' (duration: 461.399029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.977561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:29.515479Z","time spent":"462.007115ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:57:29.977579Z","caller":"traceutil/trace.go:171","msg":"trace[362397921] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"458.40139ms","start":"2026-02-19T02:57:29.519156Z","end":"2026-02-19T02:57:29.977557Z","steps":["trace[362397921] 'read index received' (duration: 407.023897ms)","trace[362397921] 'applied index is now lower than readState.Index' (duration: 51.375793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:29.977742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.575024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:29.977794Z","caller":"traceutil/trace.go:171","msg":"trace[1435699981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"458.640587ms","start":"2026-02-19T02:57:29.51914Z","end":"2026-02-19T02:57:29.97778Z","steps":["trace[1435699981] 'agreement among raft nodes before linearized reading' (duration: 458.508163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.97783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:29.519133Z","time spent":"458.690158ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:57:29.977865Z","caller":"traceutil/trace.go:171","msg":"trace[185834261] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"462.297533ms","start":"2026-02-19T02:57:29.515535Z","end":"2026-02-19T02:57:29.977832Z","steps":["trace[185834261] 'process raft request' (duration: 461.686927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:29.978021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:29.515528Z","time spent":"462.406756ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:31.383578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276896989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:57:31.884624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276896989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:57:31.959381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.540267876s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:57:31.959741Z","caller":"traceutil/trace.go:171","msg":"trace[1362656359] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"1.076242968s","start":"2026-02-19T02:57:30.883479Z","end":"2026-02-19T02:57:31.959722Z","steps":["trace[1362656359] 'read index received' (duration: 1.076062714s)","trace[1362656359] 'applied index is now lower than readState.Index' (duration: 179.564µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:31.959913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076404702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:57:31.959979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.977157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:57:31.960073Z","caller":"traceutil/trace.go:171","msg":"trace[6301878] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:714; }","duration":"641.124271ms","start":"2026-02-19T02:57:31.318938Z","end":"2026-02-19T02:57:31.960062Z","steps":["trace[6301878] 'agreement among raft nodes before linearized reading' (duration: 640.910936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:31.960132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:31.318923Z","time spent":"641.193604ms","remote":"127.0.0.1:38498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:57:31.960003Z","caller":"traceutil/trace.go:171","msg":"trace[1322703614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"1.076539436s","start":"2026-02-19T02:57:30.883443Z","end":"2026-02-19T02:57:31.959983Z","steps":["trace[1322703614] 'agreement among raft nodes before linearized reading' (duration: 1.076367042s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:31.96025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:30.883426Z","time spent":"1.076810784s","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:57:31.959798Z","caller":"traceutil/trace.go:171","msg":"trace[1020741548] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.54076753s","start":"2026-02-19T02:57:30.418999Z","end":"2026-02-19T02:57:31.959766Z","steps":["trace[1020741548] 'process raft request' (duration: 1.540606585s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:31.960788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:30.418982Z","time spent":"1.541744506s","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:57:32.097878Z","caller":"traceutil/trace.go:171","msg":"trace[1522521548] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"130.652701ms","start":"2026-02-19T02:57:31.967203Z","end":"2026-02-19T02:57:32.097856Z","steps":["trace[1522521548] 'read index received' (duration: 75.690083ms)","trace[1522521548] 'applied index is now lower than readState.Index' (duration: 54.961898ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:57:32.097951Z","caller":"traceutil/trace.go:171","msg":"trace[2018754466] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"134.616104ms","start":"2026-02-19T02:57:31.963305Z","end":"2026-02-19T02:57:32.097921Z","steps":["trace[2018754466] 'process raft request' (duration: 79.668747ms)","trace[2018754466] 'compare' (duration: 54.784383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:57:32.098083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.855506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:32.098117Z","caller":"traceutil/trace.go:171","msg":"trace[1312301416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"130.924787ms","start":"2026-02-19T02:57:31.967179Z","end":"2026-02-19T02:57:32.098103Z","steps":["trace[1312301416] 'agreement among raft nodes before linearized reading' (duration: 130.805014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:32.098231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.532489ms","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-19T02:57:32.098266Z","caller":"traceutil/trace.go:171","msg":"trace[1797142355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"116.628701ms","start":"2026-02-19T02:57:31.981629Z","end":"2026-02-19T02:57:32.098258Z","steps":["trace[1797142355] 'agreement among raft nodes before linearized reading' (duration: 116.495198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:32.098309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.948353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-19T02:57:32.098366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.57771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T02:57:32.098397Z","caller":"traceutil/trace.go:171","msg":"trace[2071872678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"116.083616ms","start":"2026-02-19T02:57:31.982291Z","end":"2026-02-19T02:57:32.098375Z","steps":["trace[2071872678] 'agreement among raft nodes before linearized reading' (duration: 115.8389ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:32.098427Z","caller":"traceutil/trace.go:171","msg":"trace[452000871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"116.664342ms","start":"2026-02-19T02:57:31.981747Z","end":"2026-02-19T02:57:32.098412Z","steps":["trace[452000871] 'agreement among raft nodes before linearized reading' (duration: 116.517148ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:52.188661Z","caller":"traceutil/trace.go:171","msg":"trace[1472701597] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"133.792623ms","start":"2026-02-19T02:57:52.054826Z","end":"2026-02-19T02:57:52.188619Z","steps":["trace[1472701597] 'process raft request' (duration: 133.580407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.719495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276897175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:57:52.867133Z","caller":"traceutil/trace.go:171","msg":"trace[1552457701] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"648.149227ms","start":"2026-02-19T02:57:52.218959Z","end":"2026-02-19T02:57:52.867108Z","steps":["trace[1552457701] 'read index received' (duration: 648.016623ms)","trace[1552457701] 'applied index is now lower than readState.Index' (duration: 129.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:57:52.867201Z","caller":"traceutil/trace.go:171","msg":"trace[1997190910] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"674.87384ms","start":"2026-02-19T02:57:52.192298Z","end":"2026-02-19T02:57:52.867172Z","steps":["trace[1997190910] 'process raft request' (duration: 674.667655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.867341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:52.192274Z","time spent":"674.980794ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:52.867492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.720906ms","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-19T02:57:52.867571Z","caller":"traceutil/trace.go:171","msg":"trace[655500467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"644.820289ms","start":"2026-02-19T02:57:52.222735Z","end":"2026-02-19T02:57:52.867555Z","steps":["trace[655500467] 'agreement among raft nodes before linearized reading' (duration: 644.674696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.867607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:52.222722Z","time spent":"644.875241ms","remote":"127.0.0.1:38250","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-19T02:57:52.867668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.168058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:57:52.867739Z","caller":"traceutil/trace.go:171","msg":"trace[1017279989] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:777; }","duration":"528.27413ms","start":"2026-02-19T02:57:52.339451Z","end":"2026-02-19T02:57:52.867725Z","steps":["trace[1017279989] 'agreement among raft nodes before linearized reading' (duration: 528.179657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.867746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.786744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T02:57:52.867771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:52.339435Z","time spent":"528.327792ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:57:52.867801Z","caller":"traceutil/trace.go:171","msg":"trace[330323756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"648.885257ms","start":"2026-02-19T02:57:52.218904Z","end":"2026-02-19T02:57:52.867789Z","steps":["trace[330323756] 'agreement among raft nodes before linearized reading' (duration: 648.336052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.867835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:52.218883Z","time spent":"648.944778ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:57:52.867947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.034078ms","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-19T02:57:52.86804Z","caller":"traceutil/trace.go:171","msg":"trace[970217306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"625.172902ms","start":"2026-02-19T02:57:52.242843Z","end":"2026-02-19T02:57:52.868015Z","steps":["trace[970217306] 'agreement among raft nodes before linearized reading' (duration: 624.904945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:52.868091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:52.242826Z","time spent":"625.256194ms","remote":"127.0.0.1:38250","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-19T02:57:52.994429Z","caller":"traceutil/trace.go:171","msg":"trace[864582368] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"121.909149ms","start":"2026-02-19T02:57:52.872492Z","end":"2026-02-19T02:57:52.994401Z","steps":["trace[864582368] 'process raft request' (duration: 121.793466ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:52.994555Z","caller":"traceutil/trace.go:171","msg":"trace[1911034338] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"122.339741ms","start":"2026-02-19T02:57:52.872194Z","end":"2026-02-19T02:57:52.994534Z","steps":["trace[1911034338] 'process raft request' (duration: 71.993425ms)","trace[1911034338] 'compare' (duration: 49.957145ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:57:52.994794Z","caller":"traceutil/trace.go:171","msg":"trace[1701056917] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"121.625072ms","start":"2026-02-19T02:57:52.873143Z","end":"2026-02-19T02:57:52.994768Z","steps":["trace[1701056917] 'process raft request' (duration: 121.202831ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:54.817216Z","caller":"traceutil/trace.go:171","msg":"trace[1339992253] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"621.72323ms","start":"2026-02-19T02:57:54.195469Z","end":"2026-02-19T02:57:54.817192Z","steps":["trace[1339992253] 'process raft request' (duration: 621.561696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:54.817393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:54.195438Z","time spent":"621.873255ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:57:55.699316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.641689ms","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-19T02:57:55.699415Z","caller":"traceutil/trace.go:171","msg":"trace[922136233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"700.784633ms","start":"2026-02-19T02:57:54.998605Z","end":"2026-02-19T02:57:55.699389Z","steps":["trace[922136233] 'range keys from in-memory index tree' (duration: 700.371972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:55.699468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:54.998587Z","time spent":"700.866996ms","remote":"127.0.0.1:38250","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-19T02:57:55.699513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.888426ms","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-19T02:57:55.699578Z","caller":"traceutil/trace.go:171","msg":"trace[332988867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"700.995708ms","start":"2026-02-19T02:57:54.998566Z","end":"2026-02-19T02:57:55.699562Z","steps":["trace[332988867] 'range keys from in-memory index tree' (duration: 700.433253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:55.699642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:54.998546Z","time spent":"701.083431ms","remote":"127.0.0.1:38250","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-19T02:57:55.69983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.864285ms","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-19T02:57:55.699899Z","caller":"traceutil/trace.go:171","msg":"trace[1238586581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"700.960598ms","start":"2026-02-19T02:57:54.998921Z","end":"2026-02-19T02:57:55.699882Z","steps":["trace[1238586581] 'range keys from in-memory index tree' (duration: 700.121036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:55.699928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:57:54.998913Z","time spent":"701.009079ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:57:56.065708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.233022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:56.065805Z","caller":"traceutil/trace.go:171","msg":"trace[1256040468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"181.336495ms","start":"2026-02-19T02:57:55.884439Z","end":"2026-02-19T02:57:56.065776Z","steps":["trace[1256040468] 'range keys from in-memory index tree' (duration: 181.068377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:57:56.0661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.774579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:57:56.066195Z","caller":"traceutil/trace.go:171","msg":"trace[1981347446] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:787; }","duration":"240.921313ms","start":"2026-02-19T02:57:55.825248Z","end":"2026-02-19T02:57:56.06617Z","steps":["trace[1981347446] 'count revisions from in-memory index tree' (duration: 240.474192ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:57.920573Z","caller":"traceutil/trace.go:171","msg":"trace[243428258] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"118.556172ms","start":"2026-02-19T02:57:57.801993Z","end":"2026-02-19T02:57:57.920549Z","steps":["trace[243428258] 'process raft request' (duration: 118.187262ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:57:57.920746Z","caller":"traceutil/trace.go:171","msg":"trace[1310959120] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"118.88687ms","start":"2026-02-19T02:57:57.801836Z","end":"2026-02-19T02:57:57.920722Z","steps":["trace[1310959120] 'process raft request' (duration: 108.167028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:08.467539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276897391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:58:08.808674Z","caller":"traceutil/trace.go:171","msg":"trace[164244114] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"841.97985ms","start":"2026-02-19T02:58:07.966657Z","end":"2026-02-19T02:58:08.808637Z","steps":["trace[164244114] 'read index received' (duration: 841.707363ms)","trace[164244114] 'applied index is now lower than readState.Index' (duration: 270.617µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:08.808728Z","caller":"traceutil/trace.go:171","msg":"trace[745131063] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"970.322429ms","start":"2026-02-19T02:58:07.838366Z","end":"2026-02-19T02:58:08.808689Z","steps":["trace[745131063] 'process raft request' (duration: 970.076363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:08.80891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:07.838354Z","time spent":"970.465213ms","remote":"127.0.0.1:38250","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-19T02:58:08.809097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.37371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T02:58:08.809214Z","caller":"traceutil/trace.go:171","msg":"trace[1623265558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"842.535914ms","start":"2026-02-19T02:58:07.966652Z","end":"2026-02-19T02:58:08.809188Z","steps":["trace[1623265558] 'agreement among raft nodes before linearized reading' (duration: 842.108173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:08.809281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:07.966616Z","time spent":"842.649097ms","remote":"127.0.0.1:38250","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-19T02:58:09.540146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.615251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:58:09.540392Z","caller":"traceutil/trace.go:171","msg":"trace[39454254] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"731.606234ms","start":"2026-02-19T02:58:08.808767Z","end":"2026-02-19T02:58:09.540373Z","steps":["trace[39454254] 'read index received' (duration: 61.794387ms)","trace[39454254] 'applied index is now lower than readState.Index' (duration: 669.810707ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:09.540642Z","caller":"traceutil/trace.go:171","msg":"trace[549599210] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"726.944081ms","start":"2026-02-19T02:58:08.81367Z","end":"2026-02-19T02:58:09.540614Z","steps":["trace[549599210] 'process raft request' (duration: 726.620363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:09.540779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:08.81364Z","time spent":"727.067134ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:58:09.542266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.918027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:09.542326Z","caller":"traceutil/trace.go:171","msg":"trace[415135471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"657.01806ms","start":"2026-02-19T02:58:08.885286Z","end":"2026-02-19T02:58:09.542305Z","steps":["trace[415135471] 'agreement among raft nodes before linearized reading' (duration: 655.296295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:09.542339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.420664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:58:09.54235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:08.885258Z","time spent":"657.087002ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:58:09.542382Z","caller":"traceutil/trace.go:171","msg":"trace[328544651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:871; }","duration":"520.493675ms","start":"2026-02-19T02:58:09.021876Z","end":"2026-02-19T02:58:09.542369Z","steps":["trace[328544651] 'agreement among raft nodes before linearized reading' (duration: 519.063027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:09.54241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:09.021858Z","time spent":"520.545047ms","remote":"127.0.0.1:38250","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-19T02:58:09.542388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575686669s","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-19T02:58:09.542462Z","caller":"traceutil/trace.go:171","msg":"trace[574054908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"1.575817863s","start":"2026-02-19T02:58:07.966626Z","end":"2026-02-19T02:58:09.542444Z","steps":["trace[574054908] 'agreement among raft nodes before linearized reading' (duration: 1.573839731s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:09.542504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:07.966605Z","time spent":"1.575889704s","remote":"127.0.0.1:38250","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-19T02:58:09.542556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.413247ms","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-19T02:58:09.542579Z","caller":"traceutil/trace.go:171","msg":"trace[430188910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"655.460159ms","start":"2026-02-19T02:58:08.887112Z","end":"2026-02-19T02:58:09.542573Z","steps":["trace[430188910] 'agreement among raft nodes before linearized reading' (duration: 653.807716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:09.542597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:08.887097Z","time spent":"655.49615ms","remote":"127.0.0.1:38250","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":"info","ts":"2026-02-19T02:58:25.911809Z","caller":"traceutil/trace.go:171","msg":"trace[1865998770] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"242.522105ms","start":"2026-02-19T02:58:25.669254Z","end":"2026-02-19T02:58:25.911776Z","steps":["trace[1865998770] 'process raft request' (duration: 191.835771ms)","trace[1865998770] 'compare' (duration: 50.458258ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:25.912055Z","caller":"traceutil/trace.go:171","msg":"trace[1105564133] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"241.591761ms","start":"2026-02-19T02:58:25.670441Z","end":"2026-02-19T02:58:25.912033Z","steps":["trace[1105564133] 'process raft request' (duration: 241.240482ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:27.206678Z","caller":"traceutil/trace.go:171","msg":"trace[1538184512] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"314.965803ms","start":"2026-02-19T02:58:26.891676Z","end":"2026-02-19T02:58:27.206641Z","steps":["trace[1538184512] 'process raft request' (duration: 314.772628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:27.206857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:26.891664Z","time spent":"315.101287ms","remote":"127.0.0.1:38250","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-19T02:58:27.433687Z","caller":"traceutil/trace.go:171","msg":"trace[1995488225] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"143.155109ms","start":"2026-02-19T02:58:27.290499Z","end":"2026-02-19T02:58:27.433654Z","steps":["trace[1995488225] 'process raft request' (duration: 79.123903ms)","trace[1995488225] 'compare' (duration: 63.830931ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:27.626742Z","caller":"traceutil/trace.go:171","msg":"trace[81421138] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"123.305487ms","start":"2026-02-19T02:58:27.5034Z","end":"2026-02-19T02:58:27.626706Z","steps":["trace[81421138] 'process raft request' (duration: 42.631253ms)","trace[81421138] 'compare' (duration: 80.435698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:58:27.865493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.598685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:58:27.865623Z","caller":"traceutil/trace.go:171","msg":"trace[996161488] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"158.005251ms","start":"2026-02-19T02:58:27.707592Z","end":"2026-02-19T02:58:27.865598Z","steps":["trace[996161488] 'process raft request' (duration: 34.19761ms)","trace[996161488] 'compare' (duration: 123.451951ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:28.048669Z","caller":"traceutil/trace.go:171","msg":"trace[1991641178] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"128.889284ms","start":"2026-02-19T02:58:27.919751Z","end":"2026-02-19T02:58:28.04864Z","steps":["trace[1991641178] 'process raft request' (duration: 97.916898ms)","trace[1991641178] 'compare' (duration: 30.855783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:58:28.971847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.509828ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:58:29.382027Z","caller":"traceutil/trace.go:171","msg":"trace[154586981] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"158.406121ms","start":"2026-02-19T02:58:29.223605Z","end":"2026-02-19T02:58:29.382011Z","steps":["trace[154586981] 'process raft request' (duration: 158.286328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.207978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276897575,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:58:30.36513Z","caller":"traceutil/trace.go:171","msg":"trace[1030384584] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"657.671996ms","start":"2026-02-19T02:58:29.707434Z","end":"2026-02-19T02:58:30.365106Z","steps":["trace[1030384584] 'read index received' (duration: 657.484521ms)","trace[1030384584] 'applied index is now lower than readState.Index' (duration: 186.725µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:30.365178Z","caller":"traceutil/trace.go:171","msg":"trace[859418425] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"685.722125ms","start":"2026-02-19T02:58:29.679418Z","end":"2026-02-19T02:58:30.36514Z","steps":["trace[859418425] 'process raft request' (duration: 685.556971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.6794Z","time spent":"685.845598ms","remote":"127.0.0.1:38070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:58:30.365386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.914782ms","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-19T02:58:30.36546Z","caller":"traceutil/trace.go:171","msg":"trace[1457767112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"658.052226ms","start":"2026-02-19T02:58:29.707395Z","end":"2026-02-19T02:58:30.365448Z","steps":["trace[1457767112] 'agreement among raft nodes before linearized reading' (duration: 657.82786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.707377Z","time spent":"658.111428ms","remote":"127.0.0.1:38250","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-19T02:58:30.365512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.752502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-19T02:58:30.365521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.042872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:30.365574Z","caller":"traceutil/trace.go:171","msg":"trace[1702485302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"397.863795ms","start":"2026-02-19T02:58:29.967693Z","end":"2026-02-19T02:58:30.365557Z","steps":["trace[1702485302] 'agreement among raft nodes before linearized reading' (duration: 397.67365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.967674Z","time spent":"397.929227ms","remote":"127.0.0.1:38250","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-19T02:58:30.365616Z","caller":"traceutil/trace.go:171","msg":"trace[1834627963] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:948; }","duration":"640.193866ms","start":"2026-02-19T02:58:29.725399Z","end":"2026-02-19T02:58:30.365593Z","steps":["trace[1834627963] 'agreement among raft nodes before linearized reading' (duration: 639.97012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.36567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.725386Z","time spent":"640.270118ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:58:30.365716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.606537ms","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-19T02:58:30.36573Z","caller":"traceutil/trace.go:171","msg":"trace[2098021696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"313.646978ms","start":"2026-02-19T02:58:30.052078Z","end":"2026-02-19T02:58:30.365725Z","steps":["trace[2098021696] 'agreement among raft nodes before linearized reading' (duration: 313.602417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:30.052061Z","time spent":"313.677629ms","remote":"127.0.0.1:38250","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-19T02:58:30.365792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.855075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:30.365805Z","caller":"traceutil/trace.go:171","msg":"trace[1067468397] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:948; }","duration":"596.901546ms","start":"2026-02-19T02:58:29.768899Z","end":"2026-02-19T02:58:30.365801Z","steps":["trace[1067468397] 'agreement among raft nodes before linearized reading' (duration: 596.881176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.768888Z","time spent":"596.925247ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T02:58:30.365834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.925317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T02:58:30.365875Z","caller":"traceutil/trace.go:171","msg":"trace[1112289771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"495.996649ms","start":"2026-02-19T02:58:29.869867Z","end":"2026-02-19T02:58:30.365864Z","steps":["trace[1112289771] 'agreement among raft nodes before linearized reading' (duration: 495.847595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:30.365906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:29.869848Z","time spent":"496.05075ms","remote":"127.0.0.1:38250","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-19T02:58:30.503571Z","caller":"traceutil/trace.go:171","msg":"trace[1019794011] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"132.660453ms","start":"2026-02-19T02:58:30.370877Z","end":"2026-02-19T02:58:30.503537Z","steps":["trace[1019794011] 'process raft request' (duration: 66.940712ms)","trace[1019794011] 'compare' (duration: 65.572697ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:30.650092Z","caller":"traceutil/trace.go:171","msg":"trace[1681685333] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"278.805111ms","start":"2026-02-19T02:58:30.371256Z","end":"2026-02-19T02:58:30.650061Z","steps":["trace[1681685333] 'process raft request' (duration: 248.221346ms)","trace[1681685333] 'compare' (duration: 30.285777ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:30.650595Z","caller":"traceutil/trace.go:171","msg":"trace[262787072] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"279.276274ms","start":"2026-02-19T02:58:30.37128Z","end":"2026-02-19T02:58:30.650557Z","steps":["trace[262787072] 'process raft request' (duration: 278.859483ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:30.650762Z","caller":"traceutil/trace.go:171","msg":"trace[704837782] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"143.597481ms","start":"2026-02-19T02:58:30.507138Z","end":"2026-02-19T02:58:30.650736Z","steps":["trace[704837782] 'process raft request' (duration: 143.483168ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:30.650943Z","caller":"traceutil/trace.go:171","msg":"trace[1988414055] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"279.583672ms","start":"2026-02-19T02:58:30.371348Z","end":"2026-02-19T02:58:30.650932Z","steps":["trace[1988414055] 'process raft request' (duration: 279.11963ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:30.651306Z","caller":"traceutil/trace.go:171","msg":"trace[460604392] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"279.747536ms","start":"2026-02-19T02:58:30.37154Z","end":"2026-02-19T02:58:30.651287Z","steps":["trace[460604392] 'process raft request' (duration: 279.004077ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:30.651391Z","caller":"traceutil/trace.go:171","msg":"trace[1064984261] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"279.027978ms","start":"2026-02-19T02:58:30.372344Z","end":"2026-02-19T02:58:30.651372Z","steps":["trace[1064984261] 'process raft request' (duration: 278.237797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:31.20747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.065003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:31.220514Z","caller":"traceutil/trace.go:171","msg":"trace[1003475282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"337.165927ms","start":"2026-02-19T02:58:30.883331Z","end":"2026-02-19T02:58:31.220497Z","steps":["trace[1003475282] 'range keys from in-memory index tree' (duration: 323.992211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:31.220579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:30.883315Z","time spent":"337.25489ms","remote":"127.0.0.1:38002","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-19T02:58:31.741377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.883418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:58:31.741594Z","caller":"traceutil/trace.go:171","msg":"trace[1691204544] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:958; }","duration":"144.125745ms","start":"2026-02-19T02:58:31.597429Z","end":"2026-02-19T02:58:31.741555Z","steps":["trace[1691204544] 'count revisions from in-memory index tree' (duration: 143.747355ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:32.899761Z","caller":"traceutil/trace.go:171","msg":"trace[1294478091] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"238.963052ms","start":"2026-02-19T02:58:32.660775Z","end":"2026-02-19T02:58:32.899738Z","steps":["trace[1294478091] 'process raft request' (duration: 238.837669ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:32.900961Z","caller":"traceutil/trace.go:171","msg":"trace[1432668364] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"240.101742ms","start":"2026-02-19T02:58:32.660841Z","end":"2026-02-19T02:58:32.900943Z","steps":["trace[1432668364] 'process raft request' (duration: 239.838925ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:32.901074Z","caller":"traceutil/trace.go:171","msg":"trace[466431824] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1000; }","duration":"216.562763ms","start":"2026-02-19T02:58:32.684492Z","end":"2026-02-19T02:58:32.901055Z","steps":["trace[466431824] 'read index received' (duration: 215.190596ms)","trace[466431824] 'applied index is now lower than readState.Index' (duration: 1.371327ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:32.901254Z","caller":"traceutil/trace.go:171","msg":"trace[2006094063] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"240.39318ms","start":"2026-02-19T02:58:32.66085Z","end":"2026-02-19T02:58:32.901244Z","steps":["trace[2006094063] 'process raft request' (duration: 239.953058ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:32.901319Z","caller":"traceutil/trace.go:171","msg":"trace[740703109] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"238.097109ms","start":"2026-02-19T02:58:32.663213Z","end":"2026-02-19T02:58:32.90131Z","steps":["trace[740703109] 'process raft request' (duration: 237.801581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:32.901377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:58:32.90157Z","caller":"traceutil/trace.go:171","msg":"trace[79938307] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:962; }","duration":"217.112377ms","start":"2026-02-19T02:58:32.684442Z","end":"2026-02-19T02:58:32.901555Z","steps":["trace[79938307] 'agreement among raft nodes before linearized reading' (duration: 216.8486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:39.976031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276897704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:58:40.476351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909320276897704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:58:40.647583Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.355083251s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:58:40.647964Z","caller":"traceutil/trace.go:171","msg":"trace[1277484309] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"1.172968052s","start":"2026-02-19T02:58:39.474972Z","end":"2026-02-19T02:58:40.64794Z","steps":["trace[1277484309] 'read index received' (duration: 1.172795637s)","trace[1277484309] 'applied index is now lower than readState.Index' (duration: 171.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:40.648109Z","caller":"traceutil/trace.go:171","msg":"trace[72292247] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.355763519s","start":"2026-02-19T02:58:39.292333Z","end":"2026-02-19T02:58:40.648096Z","steps":["trace[72292247] 'process raft request' (duration: 1.355453861s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.648251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:39.292302Z","time spent":"1.355845961s","remote":"127.0.0.1:38160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:58:40.648477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173512847s","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-19T02:58:40.648517Z","caller":"traceutil/trace.go:171","msg":"trace[1267296631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"1.173587249s","start":"2026-02-19T02:58:39.47492Z","end":"2026-02-19T02:58:40.648507Z","steps":["trace[1267296631] 'agreement among raft nodes before linearized reading' (duration: 1.173463726s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.648548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:39.474902Z","time spent":"1.17363942s","remote":"127.0.0.1:38250","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-19T02:58:40.648647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123017211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-19T02:58:40.648684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.190897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:40.648717Z","caller":"traceutil/trace.go:171","msg":"trace[963287656] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1011; }","duration":"1.123125494s","start":"2026-02-19T02:58:39.525571Z","end":"2026-02-19T02:58:40.648696Z","steps":["trace[963287656] 'agreement among raft nodes before linearized reading' (duration: 1.122849967s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:40.648738Z","caller":"traceutil/trace.go:171","msg":"trace[394224569] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1011; }","duration":"838.278949ms","start":"2026-02-19T02:58:39.810436Z","end":"2026-02-19T02:58:40.648715Z","steps":["trace[394224569] 'agreement among raft nodes before linearized reading' (duration: 838.211747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.648771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:39.52555Z","time spent":"1.123212236s","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T02:58:40.648787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:39.810416Z","time spent":"838.360321ms","remote":"127.0.0.1:38302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:58:40.648806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.185437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T02:58:40.648965Z","caller":"traceutil/trace.go:171","msg":"trace[1580686476] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1011; }","duration":"106.387592ms","start":"2026-02-19T02:58:40.54255Z","end":"2026-02-19T02:58:40.648938Z","steps":["trace[1580686476] 'agreement among raft nodes before linearized reading' (duration: 106.052603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.648534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.323798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:58:40.649119Z","caller":"traceutil/trace.go:171","msg":"trace[1870870779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"764.962524ms","start":"2026-02-19T02:58:39.884141Z","end":"2026-02-19T02:58:40.649104Z","steps":["trace[1870870779] 'agreement among raft nodes before linearized reading' (duration: 764.309017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.649164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:58:39.884125Z","time spent":"765.027707ms","remote":"127.0.0.1:38002","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-19T02:58:40.939032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.900522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:58:40.93918Z","caller":"traceutil/trace.go:171","msg":"trace[194366433] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"278.501929ms","start":"2026-02-19T02:58:40.660655Z","end":"2026-02-19T02:58:40.939157Z","steps":["trace[194366433] 'process raft request' (duration: 85.426772ms)","trace[194366433] 'compare' (duration: 192.750408ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:40.939207Z","caller":"traceutil/trace.go:171","msg":"trace[2037180046] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"278.058457ms","start":"2026-02-19T02:58:40.661127Z","end":"2026-02-19T02:58:40.939186Z","steps":["trace[2037180046] 'process raft request' (duration: 277.971494ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:40.939235Z","caller":"traceutil/trace.go:171","msg":"trace[129207535] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1055; }","duration":"239.911236ms","start":"2026-02-19T02:58:40.699313Z","end":"2026-02-19T02:58:40.939224Z","steps":["trace[129207535] 'read index received' (duration: 46.856879ms)","trace[129207535] 'applied index is now lower than readState.Index' (duration: 193.053847ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:58:40.939523Z","caller":"traceutil/trace.go:171","msg":"trace[1291458119] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"270.977811ms","start":"2026-02-19T02:58:40.668529Z","end":"2026-02-19T02:58:40.939507Z","steps":["trace[1291458119] 'process raft request' (duration: 270.650543ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:40.939528Z","caller":"traceutil/trace.go:171","msg":"trace[1219487791] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"278.012006ms","start":"2026-02-19T02:58:40.661451Z","end":"2026-02-19T02:58:40.939463Z","steps":["trace[1219487791] 'process raft request' (duration: 277.703718ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:40.939619Z","caller":"traceutil/trace.go:171","msg":"trace[1552564447] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"271.008422ms","start":"2026-02-19T02:58:40.668603Z","end":"2026-02-19T02:58:40.939612Z","steps":["trace[1552564447] 'process raft request' (duration: 270.595941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:58:40.939965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.662906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-19T02:58:40.93999Z","caller":"traceutil/trace.go:171","msg":"trace[391174741] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"240.726888ms","start":"2026-02-19T02:58:40.699256Z","end":"2026-02-19T02:58:40.939983Z","steps":["trace[391174741] 'agreement among raft nodes before linearized reading' (duration: 240.664586ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:40.940319Z","caller":"traceutil/trace.go:171","msg":"trace[728468184] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"278.242551ms","start":"2026-02-19T02:58:40.661199Z","end":"2026-02-19T02:58:40.939442Z","steps":["trace[728468184] 'process raft request' (duration: 277.933923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:44.3579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.862289ms","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-19T03:01:44.357987Z","caller":"traceutil/trace.go:171","msg":"trace[948596444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1791; }","duration":"319.990142ms","start":"2026-02-19T03:01:44.03798Z","end":"2026-02-19T03:01:44.357971Z","steps":["trace[948596444] 'range keys from in-memory index tree' (duration: 319.665553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:44.358037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:44.037962Z","time spent":"320.066274ms","remote":"127.0.0.1:38250","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-19T03:01:44.357848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.935965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T03:01:44.358145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.164461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-19T03:01:44.358236Z","caller":"traceutil/trace.go:171","msg":"trace[471627722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1791; }","duration":"352.314665ms","start":"2026-02-19T03:01:44.005902Z","end":"2026-02-19T03:01:44.358217Z","steps":["trace[471627722] 'range keys from in-memory index tree' (duration: 351.883554ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:44.358251Z","caller":"traceutil/trace.go:171","msg":"trace[1818492583] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1791; }","duration":"369.355416ms","start":"2026-02-19T03:01:43.988834Z","end":"2026-02-19T03:01:44.35819Z","steps":["trace[1818492583] 'count revisions from in-memory index tree' (duration: 368.847272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:44.358296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:44.005882Z","time spent":"352.404488ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T03:01:44.358215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.333671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T03:01:44.358322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:43.988815Z","time spent":"369.48986ms","remote":"127.0.0.1:50038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-19T03:01:44.358359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.714153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-19T03:01:44.358433Z","caller":"traceutil/trace.go:171","msg":"trace[354796384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1791; }","duration":"356.824226ms","start":"2026-02-19T03:01:44.001589Z","end":"2026-02-19T03:01:44.358413Z","steps":["trace[354796384] 'range keys from in-memory index tree' (duration: 356.555268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:44.358473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:44.001573Z","time spent":"356.891577ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T03:01:44.358381Z","caller":"traceutil/trace.go:171","msg":"trace[1986759210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1791; }","duration":"323.543476ms","start":"2026-02-19T03:01:44.034817Z","end":"2026-02-19T03:01:44.35836Z","steps":["trace[1986759210] 'range keys from in-memory index tree' (duration: 322.835937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:44.358737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:44.034796Z","time spent":"323.924046ms","remote":"127.0.0.1:38250","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-19T03:01:44.492588Z","caller":"traceutil/trace.go:171","msg":"trace[508802290] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"117.118974ms","start":"2026-02-19T03:01:44.375443Z","end":"2026-02-19T03:01:44.492561Z","steps":["trace[508802290] 'process raft request' (duration: 116.97426ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:44.520963Z","caller":"traceutil/trace.go:171","msg":"trace[1254476381] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"143.584023ms","start":"2026-02-19T03:01:44.377354Z","end":"2026-02-19T03:01:44.520938Z","steps":["trace[1254476381] 'process raft request' (duration: 143.546092ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:44.521006Z","caller":"traceutil/trace.go:171","msg":"trace[2055485095] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"145.436272ms","start":"2026-02-19T03:01:44.37554Z","end":"2026-02-19T03:01:44.520976Z","steps":["trace[2055485095] 'process raft request' (duration: 145.218946ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:44.521071Z","caller":"traceutil/trace.go:171","msg":"trace[1887626262] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"144.742854ms","start":"2026-02-19T03:01:44.376273Z","end":"2026-02-19T03:01:44.521015Z","steps":["trace[1887626262] 'process raft request' (duration: 144.58922ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:50.227752Z","caller":"traceutil/trace.go:171","msg":"trace[1762152880] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"300.123706ms","start":"2026-02-19T03:01:49.927605Z","end":"2026-02-19T03:01:50.227729Z","steps":["trace[1762152880] 'read index received' (duration: 299.944871ms)","trace[1762152880] 'applied index is now lower than readState.Index' (duration: 177.935µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:01:50.227858Z","caller":"traceutil/trace.go:171","msg":"trace[855829456] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"310.767006ms","start":"2026-02-19T03:01:49.917056Z","end":"2026-02-19T03:01:50.227823Z","steps":["trace[855829456] 'process raft request' (duration: 310.55396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:50.228007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.453144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T03:01:50.22804Z","caller":"traceutil/trace.go:171","msg":"trace[1001933163] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1819; }","duration":"284.503746ms","start":"2026-02-19T03:01:49.943525Z","end":"2026-02-19T03:01:50.228029Z","steps":["trace[1001933163] 'agreement among raft nodes before linearized reading' (duration: 284.388513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:50.22804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:49.91704Z","time spent":"310.892519ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:01:50.227885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.25993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:01:50.228189Z","caller":"traceutil/trace.go:171","msg":"trace[368401401] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1819; }","duration":"300.599939ms","start":"2026-02-19T03:01:49.927578Z","end":"2026-02-19T03:01:50.228178Z","steps":["trace[368401401] 'agreement among raft nodes before linearized reading' (duration: 300.26489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:50.228249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:49.927567Z","time spent":"300.67326ms","remote":"127.0.0.1:38132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-19T03:01:50.500416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.270639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:01:50.500509Z","caller":"traceutil/trace.go:171","msg":"trace[743572817] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"268.302919ms","start":"2026-02-19T03:01:50.232191Z","end":"2026-02-19T03:01:50.500494Z","steps":["trace[743572817] 'read index received' (duration: 144.406794ms)","trace[743572817] 'applied index is now lower than readState.Index' (duration: 123.895005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:01:50.500576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.386562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:01:50.500601Z","caller":"traceutil/trace.go:171","msg":"trace[1557782360] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1820; }","duration":"268.412723ms","start":"2026-02-19T03:01:50.232179Z","end":"2026-02-19T03:01:50.500592Z","steps":["trace[1557782360] 'agreement among raft nodes before linearized reading' (duration: 268.366812ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:50.500635Z","caller":"traceutil/trace.go:171","msg":"trace[2055856623] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"269.4722ms","start":"2026-02-19T03:01:50.231127Z","end":"2026-02-19T03:01:50.500599Z","steps":["trace[2055856623] 'process raft request' (duration: 145.568094ms)","trace[2055856623] 'compare' (duration: 123.057644ms)"],"step_count":2}