{"level":"warn","ts":"2026-08-06T19:09:00.99679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T19:09:00.996885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150: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.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-08-06T19:09:00.996978Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T19:09:00.996988Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-08-06T19:09:00.997007Z","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-08-06T19:09:00.997581Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-08-06T19:09:00.997655Z","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.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-08-06T19:09:01.001726Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.854314ms"} {"level":"info","ts":"2026-08-06T19:09:01.008202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-08-06T19:09:01.008263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T19:09:01.008287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-08-06T19:09:01.008303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T19:09:01.008315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-08-06T19:09:01.008347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-08-06T19:09:01.019035Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T19:09:01.02436Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T19:09:01.025769Z","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-08-06T19:09:01.027972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T19:09:01.028522Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T19:09:01.028687Z","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-08-06T19:09:01.028783Z","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-08-06T19:09:01.028799Z","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-08-06T19:09:01.029486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-08-06T19:09:01.029612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-08-06T19:09:01.030208Z","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-08-06T19:09:01.030363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T19:09:01.030389Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T19:09:01.030462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-08-06T19:09:01.030473Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-08-06T19:09:01.309185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T19:09:01.309315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T19:09:01.309414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-08-06T19:09:01.30959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-08-06T19:09:01.309648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-08-06T19:09:01.309691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-08-06T19:09:01.309762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-08-06T19:09:01.31059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T19:09:01.310656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T19:09:01.310743Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:09:01.310948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T19:09:01.311638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:09:01.311752Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:09:01.3143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T19:09:01.314581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"info","ts":"2026-08-06T19:09:01.314923Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T19:09:01.315236Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T19:09:01.31497Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:10:07.507969Z","caller":"traceutil/trace.go:171","msg":"trace[407291312] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"413.846208ms","start":"2026-08-06T19:10:07.094098Z","end":"2026-08-06T19:10:07.507944Z","steps":["trace[407291312] 'process raft request' (duration: 413.657903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.508648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.094079Z","time spent":"414.044102ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731158Z","caller":"traceutil/trace.go:171","msg":"trace[1716835408] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"631.125277ms","start":"2026-08-06T19:10:07.099999Z","end":"2026-08-06T19:10:07.731124Z","steps":["trace[1716835408] 'process raft request' (duration: 630.897482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.731324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.09999Z","time spent":"631.25047ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731483Z","caller":"traceutil/trace.go:171","msg":"trace[504126010] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"624.906777ms","start":"2026-08-06T19:10:07.106522Z","end":"2026-08-06T19:10:07.731429Z","steps":["trace[504126010] 'process raft request' (duration: 624.630531ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:07.731595Z","caller":"traceutil/trace.go:171","msg":"trace[213725507] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"622.166041ms","start":"2026-08-06T19:10:07.109413Z","end":"2026-08-06T19:10:07.731579Z","steps":["trace[213725507] 'process raft request' (duration: 621.773352ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:07.731666Z","caller":"traceutil/trace.go:171","msg":"trace[1515942822] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"612.020246ms","start":"2026-08-06T19:10:07.11963Z","end":"2026-08-06T19:10:07.73165Z","steps":["trace[1515942822] 'process raft request' (duration: 611.695128ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:07.731675Z","caller":"traceutil/trace.go:171","msg":"trace[1701283067] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"625.289667ms","start":"2026-08-06T19:10:07.106365Z","end":"2026-08-06T19:10:07.731655Z","steps":["trace[1701283067] 'process raft request' (duration: 624.726623ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:07.731699Z","caller":"traceutil/trace.go:171","msg":"trace[614896285] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:610; }","duration":"448.104224ms","start":"2026-08-06T19:10:07.283565Z","end":"2026-08-06T19:10:07.73167Z","steps":["trace[614896285] 'read index received' (duration: 224.297848ms)","trace[614896285] 'applied index is now lower than readState.Index' (duration: 223.805526ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:07.73182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.119612Z","time spent":"612.096308ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:07.731837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.106359Z","time spent":"625.374329ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:07.731804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.106518Z","time spent":"625.082541ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731905Z","caller":"traceutil/trace.go:171","msg":"trace[1756302973] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"477.910632ms","start":"2026-08-06T19:10:07.253988Z","end":"2026-08-06T19:10:07.731899Z","steps":["trace[1756302973] 'process raft request' (duration: 477.547564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.253969Z","time spent":"477.968553ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731847Z","caller":"traceutil/trace.go:171","msg":"trace[2001912444] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"598.51007ms","start":"2026-08-06T19:10:07.133321Z","end":"2026-08-06T19:10:07.731831Z","steps":["trace[2001912444] 'process raft request' (duration: 598.09863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.133263Z","time spent":"598.822818ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731499Z","caller":"traceutil/trace.go:171","msg":"trace[862952897] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"621.853054ms","start":"2026-08-06T19:10:07.1096Z","end":"2026-08-06T19:10:07.731453Z","steps":["trace[862952897] 'process raft request' (duration: 621.622848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.731816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.109382Z","time spent":"622.309215ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.732133Z","caller":"traceutil/trace.go:171","msg":"trace[575617759] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"275.542444ms","start":"2026-08-06T19:10:07.456502Z","end":"2026-08-06T19:10:07.732044Z","steps":["trace[575617759] 'process raft request' (duration: 275.110054ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:07.731575Z","caller":"traceutil/trace.go:171","msg":"trace[368657383] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"627.547081ms","start":"2026-08-06T19:10:07.103955Z","end":"2026-08-06T19:10:07.731502Z","steps":["trace[368657383] 'process raft request' (duration: 627.08791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.109571Z","time spent":"622.599991ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731583Z","caller":"traceutil/trace.go:171","msg":"trace[595509342] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"603.334367ms","start":"2026-08-06T19:10:07.128188Z","end":"2026-08-06T19:10:07.731523Z","steps":["trace[595509342] 'process raft request' (duration: 603.179233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.103948Z","time spent":"628.315319ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:07.731879Z","caller":"traceutil/trace.go:171","msg":"trace[428469237] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"573.264202ms","start":"2026-08-06T19:10:07.158608Z","end":"2026-08-06T19:10:07.731872Z","steps":["trace[428469237] 'process raft request' (duration: 572.861712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.128171Z","time spent":"604.271389ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:07.732269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.688288ms","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-08-06T19:10:07.732845Z","caller":"traceutil/trace.go:171","msg":"trace[1400273422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"449.267653ms","start":"2026-08-06T19:10:07.283559Z","end":"2026-08-06T19:10:07.732826Z","steps":["trace[1400273422] 'agreement among raft nodes before linearized reading' (duration: 448.485703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:07.732913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.283515Z","time spent":"449.380275ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T19:10:07.732748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.158581Z","time spent":"574.119863ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:08.347386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.405129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:10:08.347637Z","caller":"traceutil/trace.go:171","msg":"trace[2033912989] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"615.821399ms","start":"2026-08-06T19:10:07.731799Z","end":"2026-08-06T19:10:08.347621Z","steps":["trace[2033912989] 'read index received' (duration: 496.046791ms)","trace[2033912989] 'applied index is now lower than readState.Index' (duration: 119.773548ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:10:08.347706Z","caller":"traceutil/trace.go:171","msg":"trace[1960241697] transaction","detail":"{read_only:false; number_of_response:1; response_revision:600; }","duration":"832.101622ms","start":"2026-08-06T19:10:07.515595Z","end":"2026-08-06T19:10:08.347697Z","steps":["trace[1960241697] 'process raft request' (duration: 831.9881ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:08.347767Z","caller":"traceutil/trace.go:171","msg":"trace[204135258] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"832.637386ms","start":"2026-08-06T19:10:07.515061Z","end":"2026-08-06T19:10:08.347699Z","steps":["trace[204135258] 'process raft request' (duration: 832.446441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.347969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.515589Z","time spent":"832.155685ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:08.347984Z","caller":"traceutil/trace.go:171","msg":"trace[594353950] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"833.23612ms","start":"2026-08-06T19:10:07.514702Z","end":"2026-08-06T19:10:08.347938Z","steps":["trace[594353950] 'process raft request' (duration: 713.068383ms)","trace[594353950] 'compare' (duration: 119.059971ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:08.348124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.51467Z","time spent":"833.387863ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:08.348114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05760609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T19:10:08.348118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.515055Z","time spent":"832.783799ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:10:08.348134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015968085s","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-08-06T19:10:08.348185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.453078ms","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-08-06T19:10:08.348183Z","caller":"traceutil/trace.go:171","msg":"trace[2059650814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.057705093s","start":"2026-08-06T19:10:07.290461Z","end":"2026-08-06T19:10:08.348166Z","steps":["trace[2059650814] 'agreement among raft nodes before linearized reading' (duration: 1.057543609s)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:08.348208Z","caller":"traceutil/trace.go:171","msg":"trace[1419814520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.016074348s","start":"2026-08-06T19:10:07.332115Z","end":"2026-08-06T19:10:08.34819Z","steps":["trace[1419814520] 'agreement among raft nodes before linearized reading' (duration: 1.015863483s)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:08.348222Z","caller":"traceutil/trace.go:171","msg":"trace[1875852041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"841.52289ms","start":"2026-08-06T19:10:07.506687Z","end":"2026-08-06T19:10:08.34821Z","steps":["trace[1875852041] 'agreement among raft nodes before linearized reading' (duration: 841.334005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.348237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.290448Z","time spent":"1.057780454s","remote":"127.0.0.1:44162","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":"warn","ts":"2026-08-06T19:10:08.348254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.506669Z","time spent":"841.577402ms","remote":"127.0.0.1:44162","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-08-06T19:10:08.348256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.332097Z","time spent":"1.0161479s","remote":"127.0.0.1:44162","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-08-06T19:10:08.349387Z","caller":"traceutil/trace.go:171","msg":"trace[1335491867] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"602.818025ms","start":"2026-08-06T19:10:07.746551Z","end":"2026-08-06T19:10:08.349369Z","steps":["trace[1335491867] 'process raft request' (duration: 602.296662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.349458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.746547Z","time spent":"602.879178ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:08.349743Z","caller":"traceutil/trace.go:171","msg":"trace[1271638529] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"609.413364ms","start":"2026-08-06T19:10:07.740319Z","end":"2026-08-06T19:10:08.349732Z","steps":["trace[1271638529] 'process raft request' (duration: 608.430789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.349807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.740301Z","time spent":"609.467066ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:08.349949Z","caller":"traceutil/trace.go:171","msg":"trace[247311141] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"603.443151ms","start":"2026-08-06T19:10:07.746495Z","end":"2026-08-06T19:10:08.349938Z","steps":["trace[247311141] 'process raft request' (duration: 602.325813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.353118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.746476Z","time spent":"606.605116ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:08.350592Z","caller":"traceutil/trace.go:171","msg":"trace[1900592001] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"603.682855ms","start":"2026-08-06T19:10:07.746894Z","end":"2026-08-06T19:10:08.350577Z","steps":["trace[1900592001] 'process raft request' (duration: 601.980163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.35358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.746889Z","time spent":"606.626876ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:08.351065Z","caller":"traceutil/trace.go:171","msg":"trace[2107137120] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"601.480672ms","start":"2026-08-06T19:10:07.749573Z","end":"2026-08-06T19:10:08.351054Z","steps":["trace[2107137120] 'process raft request' (duration: 599.3296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.354019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.749563Z","time spent":"604.378732ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:08.351106Z","caller":"traceutil/trace.go:171","msg":"trace[1693356577] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"600.407676ms","start":"2026-08-06T19:10:07.750692Z","end":"2026-08-06T19:10:08.3511Z","steps":["trace[1693356577] 'process raft request' (duration: 598.244605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.35434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.750686Z","time spent":"603.597073ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:08.35213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.10091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:10:08.354483Z","caller":"traceutil/trace.go:171","msg":"trace[1397852201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"354.486316ms","start":"2026-08-06T19:10:07.999981Z","end":"2026-08-06T19:10:08.354467Z","steps":["trace[1397852201] 'agreement among raft nodes before linearized reading' (duration: 352.11297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.354551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.999959Z","time spent":"354.566179ms","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T19:10:08.352164Z","caller":"traceutil/trace.go:171","msg":"trace[323441434] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"597.968448ms","start":"2026-08-06T19:10:07.754186Z","end":"2026-08-06T19:10:08.352154Z","steps":["trace[323441434] 'process raft request' (duration: 594.785812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.354777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.754179Z","time spent":"600.53259ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:08.352302Z","caller":"traceutil/trace.go:171","msg":"trace[923924260] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"596.912073ms","start":"2026-08-06T19:10:07.755383Z","end":"2026-08-06T19:10:08.352295Z","steps":["trace[923924260] 'process raft request' (duration: 593.645904ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:08.352337Z","caller":"traceutil/trace.go:171","msg":"trace[229881017] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"593.274114ms","start":"2026-08-06T19:10:07.759057Z","end":"2026-08-06T19:10:08.352331Z","steps":["trace[229881017] 'process raft request' (duration: 592.955727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.355603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.759048Z","time spent":"596.478483ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:08.352357Z","caller":"traceutil/trace.go:171","msg":"trace[1671119063] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"569.913102ms","start":"2026-08-06T19:10:07.782438Z","end":"2026-08-06T19:10:08.352351Z","steps":["trace[1671119063] 'process raft request' (duration: 569.623205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:08.355689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.78241Z","time spent":"573.266722ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:08.35498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:07.755375Z","time spent":"599.561967ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:10:14.467541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.454378ms","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-08-06T19:10:14.467658Z","caller":"traceutil/trace.go:171","msg":"trace[1990794376] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:692; }","duration":"167.626903ms","start":"2026-08-06T19:10:14.299991Z","end":"2026-08-06T19:10:14.467618Z","steps":["trace[1990794376] 'count revisions from in-memory index tree' (duration: 167.370346ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:14.544584Z","caller":"traceutil/trace.go:171","msg":"trace[110405603] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"169.672951ms","start":"2026-08-06T19:10:14.374879Z","end":"2026-08-06T19:10:14.544552Z","steps":["trace[110405603] 'read index received' (duration: 168.79031ms)","trace[110405603] 'applied index is now lower than readState.Index' (duration: 881.271µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:10:14.54461Z","caller":"traceutil/trace.go:171","msg":"trace[2028270396] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"202.876651ms","start":"2026-08-06T19:10:14.341713Z","end":"2026-08-06T19:10:14.54459Z","steps":["trace[2028270396] 'process raft request' (duration: 202.262056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:14.544813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.927307ms","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-08-06T19:10:14.544875Z","caller":"traceutil/trace.go:171","msg":"trace[1409793103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"170.017998ms","start":"2026-08-06T19:10:14.374845Z","end":"2026-08-06T19:10:14.544863Z","steps":["trace[1409793103] 'agreement among raft nodes before linearized reading' (duration: 169.826254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:14.544994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.583643ms","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-08-06T19:10:14.545078Z","caller":"traceutil/trace.go:171","msg":"trace[1245419481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"165.723066ms","start":"2026-08-06T19:10:14.379334Z","end":"2026-08-06T19:10:14.545057Z","steps":["trace[1245419481] 'agreement among raft nodes before linearized reading' (duration: 165.48532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:14.545128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.302039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kbnwf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-06T19:10:14.545115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.957733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T19:10:14.545201Z","caller":"traceutil/trace.go:171","msg":"trace[219950204] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kbnwf; range_end:; response_count:1; response_revision:693; }","duration":"127.416082ms","start":"2026-08-06T19:10:14.417762Z","end":"2026-08-06T19:10:14.545179Z","steps":["trace[219950204] 'agreement among raft nodes before linearized reading' (duration: 127.276109ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:14.545203Z","caller":"traceutil/trace.go:171","msg":"trace[1430212094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"154.076685ms","start":"2026-08-06T19:10:14.391111Z","end":"2026-08-06T19:10:14.545188Z","steps":["trace[1430212094] 'agreement among raft nodes before linearized reading' (duration: 153.768558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:14.545337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.433484ms","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-08-06T19:10:14.545407Z","caller":"traceutil/trace.go:171","msg":"trace[1109404752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"154.538546ms","start":"2026-08-06T19:10:14.390847Z","end":"2026-08-06T19:10:14.545386Z","steps":["trace[1109404752] 'agreement among raft nodes before linearized reading' (duration: 154.264869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.202253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.780203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:10:15.202376Z","caller":"traceutil/trace.go:171","msg":"trace[1297456515] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"653.054426ms","start":"2026-08-06T19:10:14.549299Z","end":"2026-08-06T19:10:15.202353Z","steps":["trace[1297456515] 'process raft request' (duration: 218.077198ms)","trace[1297456515] 'compare' (duration: 434.390994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:15.20246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.549276Z","time spent":"653.144788ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:15.25446Z","caller":"traceutil/trace.go:171","msg":"trace[1090920691] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"704.751313ms","start":"2026-08-06T19:10:14.549693Z","end":"2026-08-06T19:10:15.254444Z","steps":["trace[1090920691] 'process raft request' (duration: 704.563678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.254603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.54966Z","time spent":"704.868996ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:15.254839Z","caller":"traceutil/trace.go:171","msg":"trace[174713963] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"704.899246ms","start":"2026-08-06T19:10:14.549883Z","end":"2026-08-06T19:10:15.254782Z","steps":["trace[174713963] 'process raft request' (duration: 704.498097ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:15.255104Z","caller":"traceutil/trace.go:171","msg":"trace[30981498] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"697.462816ms","start":"2026-08-06T19:10:14.557624Z","end":"2026-08-06T19:10:15.255087Z","steps":["trace[30981498] 'process raft request' (duration: 697.169909ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:15.254879Z","caller":"traceutil/trace.go:171","msg":"trace[340428756] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"702.336165ms","start":"2026-08-06T19:10:14.552519Z","end":"2026-08-06T19:10:15.254855Z","steps":["trace[340428756] 'process raft request' (duration: 702.14149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.255201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.557585Z","time spent":"697.575569ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:10:15.255274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.552504Z","time spent":"702.667792ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:10:15.255873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.549873Z","time spent":"705.091611ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:15.256776Z","caller":"traceutil/trace.go:171","msg":"trace[1323218321] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:720; }","duration":"604.71649ms","start":"2026-08-06T19:10:14.652043Z","end":"2026-08-06T19:10:15.25676Z","steps":["trace[1323218321] 'read index received' (duration: 115.371362ms)","trace[1323218321] 'applied index is now lower than readState.Index' (duration: 489.343618ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:10:15.256808Z","caller":"traceutil/trace.go:171","msg":"trace[716483920] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"655.381882ms","start":"2026-08-06T19:10:14.601403Z","end":"2026-08-06T19:10:15.256785Z","steps":["trace[716483920] 'process raft request' (duration: 653.540838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.256894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.601367Z","time spent":"655.485814ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:15.256905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.870104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T19:10:15.256938Z","caller":"traceutil/trace.go:171","msg":"trace[1026724851] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:699; }","duration":"604.949086ms","start":"2026-08-06T19:10:14.651979Z","end":"2026-08-06T19:10:15.256928Z","steps":["trace[1026724851] 'agreement among raft nodes before linearized reading' (duration: 604.857734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.256968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.651949Z","time spent":"605.012018ms","remote":"127.0.0.1:43880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"info","ts":"2026-08-06T19:10:15.40084Z","caller":"traceutil/trace.go:171","msg":"trace[1356621315] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"144.004783ms","start":"2026-08-06T19:10:15.256815Z","end":"2026-08-06T19:10:15.40082Z","steps":["trace[1356621315] 'read index received' (duration: 69.085057ms)","trace[1356621315] 'applied index is now lower than readState.Index' (duration: 74.918606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:15.400983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.378243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:10:15.401038Z","caller":"traceutil/trace.go:171","msg":"trace[340498868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"402.479554ms","start":"2026-08-06T19:10:14.998549Z","end":"2026-08-06T19:10:15.401029Z","steps":["trace[340498868] 'agreement among raft nodes before linearized reading' (duration: 402.388232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:15.40107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.998528Z","time spent":"402.534736ms","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T19:10:15.401089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.524824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-06T19:10:15.401157Z","caller":"traceutil/trace.go:171","msg":"trace[879268992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:700; }","duration":"139.628297ms","start":"2026-08-06T19:10:15.261511Z","end":"2026-08-06T19:10:15.40114Z","steps":["trace[879268992] 'agreement among raft nodes before linearized reading' (duration: 139.510124ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:15.400854Z","caller":"traceutil/trace.go:171","msg":"trace[812391799] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"604.916765ms","start":"2026-08-06T19:10:14.795913Z","end":"2026-08-06T19:10:15.40083Z","steps":["trace[812391799] 'process raft request' (duration: 530.03153ms)","trace[812391799] 'compare' (duration: 74.741772ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:15.401265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:14.795889Z","time spent":"605.313315ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:10:17.065574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996595464427057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T19:10:17.16847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440468492s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:10:17.168547Z","caller":"traceutil/trace.go:171","msg":"trace[636043732] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.68592853s","start":"2026-08-06T19:10:15.482605Z","end":"2026-08-06T19:10:17.168533Z","steps":["trace[636043732] 'process raft request' (duration: 245.330046ms)","trace[636043732] 'compare' (duration: 1.440231056s)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:17.168586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:15.482582Z","time spent":"1.685987982s","remote":"127.0.0.1:43948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:10:17.190793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46277971s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T19:10:17.191108Z","caller":"traceutil/trace.go:171","msg":"trace[872445631] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.707262815s","start":"2026-08-06T19:10:15.483831Z","end":"2026-08-06T19:10:17.191094Z","steps":["trace[872445631] 'process raft request' (duration: 1.707121632s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:17.191206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:15.483815Z","time spent":"1.707326837s","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:17.191108Z","caller":"traceutil/trace.go:171","msg":"trace[980237368] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.707119601s","start":"2026-08-06T19:10:15.483973Z","end":"2026-08-06T19:10:17.191092Z","steps":["trace[980237368] 'process raft request' (duration: 1.70706251s)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:17.191223Z","caller":"traceutil/trace.go:171","msg":"trace[1823984191] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:731; }","duration":"626.370853ms","start":"2026-08-06T19:10:16.564835Z","end":"2026-08-06T19:10:17.191206Z","steps":["trace[1823984191] 'read index received' (duration: 626.068756ms)","trace[1823984191] 'applied index is now lower than readState.Index' (duration: 300.657µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:17.191323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:15.483967Z","time spent":"1.707312687s","remote":"127.0.0.1:43948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:17.191329Z","caller":"traceutil/trace.go:171","msg":"trace[1012859815] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.706242209s","start":"2026-08-06T19:10:15.485067Z","end":"2026-08-06T19:10:17.191309Z","steps":["trace[1012859815] 'process raft request' (duration: 1.705994674s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:17.191409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.727189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:10:17.191398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.565418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:10:17.191439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.609233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:10:17.191442Z","caller":"traceutil/trace.go:171","msg":"trace[1297829156] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:706; }","duration":"421.79149ms","start":"2026-08-06T19:10:16.769642Z","end":"2026-08-06T19:10:17.191434Z","steps":["trace[1297829156] 'agreement among raft nodes before linearized reading' (duration: 421.739939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:17.191425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:15.485059Z","time spent":"1.706314802s","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:17.191461Z","caller":"traceutil/trace.go:171","msg":"trace[1951988696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"187.653485ms","start":"2026-08-06T19:10:17.003801Z","end":"2026-08-06T19:10:17.191455Z","steps":["trace[1951988696] 'agreement among raft nodes before linearized reading' (duration: 187.614474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:17.191471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:16.769625Z","time spent":"421.836772ms","remote":"127.0.0.1:44008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-06T19:10:17.191467Z","caller":"traceutil/trace.go:171","msg":"trace[1302672648] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:706; }","duration":"626.63403ms","start":"2026-08-06T19:10:16.564815Z","end":"2026-08-06T19:10:17.191449Z","steps":["trace[1302672648] 'agreement among raft nodes before linearized reading' (duration: 626.541288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:17.191531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:16.564747Z","time spent":"626.748342ms","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:10:17.19152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.663892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:10:17.191624Z","caller":"traceutil/trace.go:171","msg":"trace[31452067] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:706; }","duration":"252.799135ms","start":"2026-08-06T19:10:16.938809Z","end":"2026-08-06T19:10:17.191608Z","steps":["trace[31452067] 'agreement among raft nodes before linearized reading' (duration: 252.61136ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:18.92353Z","caller":"traceutil/trace.go:171","msg":"trace[882109325] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"380.446833ms","start":"2026-08-06T19:10:18.543062Z","end":"2026-08-06T19:10:18.923509Z","steps":["trace[882109325] 'process raft request' (duration: 380.237088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:18.923643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:18.54301Z","time spent":"380.580616ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T19:10:18.926015Z","caller":"traceutil/trace.go:171","msg":"trace[1766906296] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"183.437462ms","start":"2026-08-06T19:10:18.742556Z","end":"2026-08-06T19:10:18.925993Z","steps":["trace[1766906296] 'process raft request' (duration: 183.35252ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:28.721843Z","caller":"traceutil/trace.go:171","msg":"trace[1995538528] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"198.602868ms","start":"2026-08-06T19:10:28.523216Z","end":"2026-08-06T19:10:28.721819Z","steps":["trace[1995538528] 'process raft request' (duration: 198.466235ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:31.404826Z","caller":"traceutil/trace.go:171","msg":"trace[1385366395] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"127.211008ms","start":"2026-08-06T19:10:31.277582Z","end":"2026-08-06T19:10:31.404793Z","steps":["trace[1385366395] 'process raft request' (duration: 127.061405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:32.212287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.95089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:10:32.212554Z","caller":"traceutil/trace.go:171","msg":"trace[287432964] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"803.229598ms","start":"2026-08-06T19:10:31.409299Z","end":"2026-08-06T19:10:32.212529Z","steps":["trace[287432964] 'process raft request' (duration: 803.187407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:32.212629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:31.409249Z","time spent":"803.34937ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:32.212749Z","caller":"traceutil/trace.go:171","msg":"trace[1286809484] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"803.75191ms","start":"2026-08-06T19:10:31.408988Z","end":"2026-08-06T19:10:32.21274Z","steps":["trace[1286809484] 'process raft request' (duration: 44.266337ms)","trace[1286809484] 'compare' (duration: 758.826827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:10:32.212799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:31.408966Z","time spent":"803.809771ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:32.212875Z","caller":"traceutil/trace.go:171","msg":"trace[664418521] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"803.682118ms","start":"2026-08-06T19:10:31.409165Z","end":"2026-08-06T19:10:32.212847Z","steps":["trace[664418521] 'process raft request' (duration: 803.225167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:32.213172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:31.409157Z","time spent":"803.908363ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:32.221239Z","caller":"traceutil/trace.go:171","msg":"trace[396575354] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"682.096326ms","start":"2026-08-06T19:10:31.539133Z","end":"2026-08-06T19:10:32.22123Z","steps":["trace[396575354] 'process raft request' (duration: 681.960193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:32.22132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:31.539109Z","time spent":"682.176148ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:11:02.150643Z","caller":"traceutil/trace.go:171","msg":"trace[1056392915] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"151.764457ms","start":"2026-08-06T19:11:01.99886Z","end":"2026-08-06T19:11:02.150624Z","steps":["trace[1056392915] 'read index received' (duration: 151.611833ms)","trace[1056392915] 'applied index is now lower than readState.Index' (duration: 151.644µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:02.150698Z","caller":"traceutil/trace.go:171","msg":"trace[1729592920] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"589.172476ms","start":"2026-08-06T19:11:01.56149Z","end":"2026-08-06T19:11:02.150663Z","steps":["trace[1729592920] 'process raft request' (duration: 589.027172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:02.150734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.856129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:11:02.150765Z","caller":"traceutil/trace.go:171","msg":"trace[1093455697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"151.931211ms","start":"2026-08-06T19:11:01.998828Z","end":"2026-08-06T19:11:02.150759Z","steps":["trace[1093455697] 'agreement among raft nodes before linearized reading' (duration: 151.868109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:02.150869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:01.561473Z","time spent":"589.309718ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:11:08.510206Z","caller":"traceutil/trace.go:171","msg":"trace[1627106161] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"331.426386ms","start":"2026-08-06T19:11:08.178733Z","end":"2026-08-06T19:11:08.51016Z","steps":["trace[1627106161] 'process raft request' (duration: 331.153869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:08.510447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.178711Z","time spent":"331.603819ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:10.021994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076476677s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:11:10.022102Z","caller":"traceutil/trace.go:171","msg":"trace[2015889050] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.507727197s","start":"2026-08-06T19:11:08.514356Z","end":"2026-08-06T19:11:10.022084Z","steps":["trace[2015889050] 'process raft request' (duration: 431.079426ms)","trace[2015889050] 'compare' (duration: 1.076025606s)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:11:10.02219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.514333Z","time spent":"1.507826788s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:10.023343Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077946722s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T19:11:10.02373Z","caller":"traceutil/trace.go:171","msg":"trace[432300639] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.508823372s","start":"2026-08-06T19:11:08.51488Z","end":"2026-08-06T19:11:10.023703Z","steps":["trace[432300639] 'process raft request' (duration: 1.508596007s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:10.02396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.514866Z","time spent":"1.508985087s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:11:10.024048Z","caller":"traceutil/trace.go:171","msg":"trace[582384685] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.508982817s","start":"2026-08-06T19:11:08.515033Z","end":"2026-08-06T19:11:10.024016Z","steps":["trace[582384685] 'process raft request' (duration: 1.508599777s)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:10.024087Z","caller":"traceutil/trace.go:171","msg":"trace[1630891099] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1124; }","duration":"1.414997253s","start":"2026-08-06T19:11:08.60905Z","end":"2026-08-06T19:11:10.024047Z","steps":["trace[1630891099] 'read index received' (duration: 336.359614ms)","trace[1630891099] 'applied index is now lower than readState.Index' (duration: 1.078636279s)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:11:10.024148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.515027Z","time spent":"1.509070039s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:11:10.024173Z","caller":"traceutil/trace.go:171","msg":"trace[702023124] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"1.312924074s","start":"2026-08-06T19:11:08.711232Z","end":"2026-08-06T19:11:10.024156Z","steps":["trace[702023124] 'process raft request' (duration: 1.312611615s)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:10.024244Z","caller":"traceutil/trace.go:171","msg":"trace[70395831] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.204395839s","start":"2026-08-06T19:11:08.819836Z","end":"2026-08-06T19:11:10.024232Z","steps":["trace[70395831] 'process raft request' (duration: 1.204163582s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:10.024268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.711212Z","time spent":"1.312999015s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:10.024376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.819816Z","time spent":"1.20445978s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:10.028843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419769697s","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-08-06T19:11:10.028886Z","caller":"traceutil/trace.go:171","msg":"trace[788510555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"1.41987134s","start":"2026-08-06T19:11:08.609005Z","end":"2026-08-06T19:11:10.028877Z","steps":["trace[788510555] 'agreement among raft nodes before linearized reading' (duration: 1.41527494s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:10.029109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03181163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:11:10.029138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.60899Z","time spent":"1.420138456s","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T19:11:10.02919Z","caller":"traceutil/trace.go:171","msg":"trace[1106802432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"1.031935223s","start":"2026-08-06T19:11:08.997235Z","end":"2026-08-06T19:11:10.02917Z","steps":["trace[1106802432] 'agreement among raft nodes before linearized reading' (duration: 1.03181067s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:10.029232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:08.997219Z","time spent":"1.032006934s","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T19:11:10.02939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.244021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:11:10.029417Z","caller":"traceutil/trace.go:171","msg":"trace[1016460111] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1089; }","duration":"546.295362ms","start":"2026-08-06T19:11:09.483113Z","end":"2026-08-06T19:11:10.029409Z","steps":["trace[1016460111] 'agreement among raft nodes before linearized reading' (duration: 546.248061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:10.02944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:09.483095Z","time spent":"546.338643ms","remote":"127.0.0.1:44278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-06T19:11:28.886858Z","caller":"traceutil/trace.go:171","msg":"trace[1641190955] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"196.345571ms","start":"2026-08-06T19:11:28.690488Z","end":"2026-08-06T19:11:28.886833Z","steps":["trace[1641190955] 'read index received' (duration: 196.195447ms)","trace[1641190955] 'applied index is now lower than readState.Index' (duration: 148.574µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:28.887012Z","caller":"traceutil/trace.go:171","msg":"trace[811292294] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"269.652466ms","start":"2026-08-06T19:11:28.617306Z","end":"2026-08-06T19:11:28.886959Z","steps":["trace[811292294] 'process raft request' (duration: 269.346509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:28.887112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.603597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:11:28.887161Z","caller":"traceutil/trace.go:171","msg":"trace[752134068] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1150; }","duration":"196.722209ms","start":"2026-08-06T19:11:28.690423Z","end":"2026-08-06T19:11:28.887145Z","steps":["trace[752134068] 'agreement among raft nodes before linearized reading' (duration: 196.515695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:29.116901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.416939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:11:29.116988Z","caller":"traceutil/trace.go:171","msg":"trace[815236529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"117.557372ms","start":"2026-08-06T19:11:28.99941Z","end":"2026-08-06T19:11:29.116967Z","steps":["trace[815236529] 'range keys from in-memory index tree' (duration: 117.283566ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:30.29409Z","caller":"traceutil/trace.go:171","msg":"trace[1391595544] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1195; }","duration":"191.020283ms","start":"2026-08-06T19:11:30.103029Z","end":"2026-08-06T19:11:30.29405Z","steps":["trace[1391595544] 'read index received' (duration: 190.749796ms)","trace[1391595544] 'applied index is now lower than readState.Index' (duration: 268.757µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:30.294206Z","caller":"traceutil/trace.go:171","msg":"trace[2147221533] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"194.950686ms","start":"2026-08-06T19:11:30.099216Z","end":"2026-08-06T19:11:30.294166Z","steps":["trace[2147221533] 'process raft request' (duration: 194.572097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:30.294556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.478723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:11:30.294615Z","caller":"traceutil/trace.go:171","msg":"trace[1497892026] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1151; }","duration":"191.616737ms","start":"2026-08-06T19:11:30.102983Z","end":"2026-08-06T19:11:30.2946Z","steps":["trace[1497892026] 'agreement among raft nodes before linearized reading' (duration: 191.260338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:30.30205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.828631ms","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-08-06T19:11:30.302113Z","caller":"traceutil/trace.go:171","msg":"trace[61547772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1152; }","duration":"181.932334ms","start":"2026-08-06T19:11:30.120163Z","end":"2026-08-06T19:11:30.302095Z","steps":["trace[61547772] 'agreement among raft nodes before linearized reading' (duration: 181.653457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:30.302139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.61764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-06T19:11:30.302227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.986778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-06T19:11:30.302252Z","caller":"traceutil/trace.go:171","msg":"trace[337873332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"138.820914ms","start":"2026-08-06T19:11:30.163405Z","end":"2026-08-06T19:11:30.302226Z","steps":["trace[337873332] 'agreement among raft nodes before linearized reading' (duration: 138.440265ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:30.302304Z","caller":"traceutil/trace.go:171","msg":"trace[51807602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"139.094241ms","start":"2026-08-06T19:11:30.16319Z","end":"2026-08-06T19:11:30.302284Z","steps":["trace[51807602] 'agreement among raft nodes before linearized reading' (duration: 138.878716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:30.302364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.872264ms","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-08-06T19:11:30.302397Z","caller":"traceutil/trace.go:171","msg":"trace[29882375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1152; }","duration":"179.944466ms","start":"2026-08-06T19:11:30.122443Z","end":"2026-08-06T19:11:30.302388Z","steps":["trace[29882375] 'agreement among raft nodes before linearized reading' (duration: 179.853124ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:30.526777Z","caller":"traceutil/trace.go:171","msg":"trace[1683936457] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"218.704899ms","start":"2026-08-06T19:11:30.308032Z","end":"2026-08-06T19:11:30.526737Z","steps":["trace[1683936457] 'process raft request' (duration: 182.936098ms)","trace[1683936457] 'compare' (duration: 35.525305ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:30.527193Z","caller":"traceutil/trace.go:171","msg":"trace[1537666088] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"218.508364ms","start":"2026-08-06T19:11:30.308654Z","end":"2026-08-06T19:11:30.527162Z","steps":["trace[1537666088] 'process raft request' (duration: 218.026692ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:30.527302Z","caller":"traceutil/trace.go:171","msg":"trace[1925828491] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"218.298898ms","start":"2026-08-06T19:11:30.308968Z","end":"2026-08-06T19:11:30.527267Z","steps":["trace[1925828491] 'process raft request' (duration: 218.088063ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:31.296588Z","caller":"traceutil/trace.go:171","msg":"trace[668066463] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"297.829485ms","start":"2026-08-06T19:11:30.998734Z","end":"2026-08-06T19:11:31.296563Z","steps":["trace[668066463] 'read index received' (duration: 297.639361ms)","trace[668066463] 'applied index is now lower than readState.Index' (duration: 189.104µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:11:31.296729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.98363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:11:31.296769Z","caller":"traceutil/trace.go:171","msg":"trace[1599326785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"298.066841ms","start":"2026-08-06T19:11:30.998691Z","end":"2026-08-06T19:11:31.296757Z","steps":["trace[1599326785] 'agreement among raft nodes before linearized reading' (duration: 297.965238ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:31.296987Z","caller":"traceutil/trace.go:171","msg":"trace[2034279851] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"401.471343ms","start":"2026-08-06T19:11:30.89549Z","end":"2026-08-06T19:11:31.296961Z","steps":["trace[2034279851] 'process raft request' (duration: 400.94803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:31.297166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:30.89547Z","time spent":"401.619576ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:47.752559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996595464428262,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T19:11:47.789161Z","caller":"traceutil/trace.go:171","msg":"trace[1462099210] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"537.569259ms","start":"2026-08-06T19:11:47.251569Z","end":"2026-08-06T19:11:47.789138Z","steps":["trace[1462099210] 'read index received' (duration: 537.331553ms)","trace[1462099210] 'applied index is now lower than readState.Index' (duration: 236.686µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:47.789241Z","caller":"traceutil/trace.go:171","msg":"trace[415900968] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"752.604977ms","start":"2026-08-06T19:11:47.036603Z","end":"2026-08-06T19:11:47.789208Z","steps":["trace[415900968] 'process raft request' (duration: 752.384932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:47.789312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.723022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:11:47.789355Z","caller":"traceutil/trace.go:171","msg":"trace[627090961] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1260; }","duration":"537.815946ms","start":"2026-08-06T19:11:47.251527Z","end":"2026-08-06T19:11:47.789343Z","steps":["trace[627090961] 'agreement among raft nodes before linearized reading' (duration: 537.718863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:47.789387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:47.03658Z","time spent":"752.73143ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:47.789397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:47.251513Z","time spent":"537.874917ms","remote":"127.0.0.1:44356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:11:47.789501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.895757ms","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-08-06T19:11:47.78958Z","caller":"traceutil/trace.go:171","msg":"trace[1951334691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"423.02756ms","start":"2026-08-06T19:11:47.366533Z","end":"2026-08-06T19:11:47.78956Z","steps":["trace[1951334691] 'agreement among raft nodes before linearized reading' (duration: 422.791663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:47.789622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:47.36652Z","time spent":"423.093461ms","remote":"127.0.0.1:44162","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-08-06T19:11:48.499584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996595464428265,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T19:11:48.536261Z","caller":"traceutil/trace.go:171","msg":"trace[863349253] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1310; }","duration":"537.15716ms","start":"2026-08-06T19:11:47.999084Z","end":"2026-08-06T19:11:48.536242Z","steps":["trace[863349253] 'read index received' (duration: 535.463517ms)","trace[863349253] 'applied index is now lower than readState.Index' (duration: 1.692863ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:48.536606Z","caller":"traceutil/trace.go:171","msg":"trace[1027965649] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"178.623509ms","start":"2026-08-06T19:11:48.357957Z","end":"2026-08-06T19:11:48.536581Z","steps":["trace[1027965649] 'process raft request' (duration: 178.236439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:48.536622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.518949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:11:48.536613Z","caller":"traceutil/trace.go:171","msg":"trace[1642732552] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"743.89307ms","start":"2026-08-06T19:11:47.792667Z","end":"2026-08-06T19:11:48.53656Z","steps":["trace[1642732552] 'process raft request' (duration: 741.774497ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:48.536686Z","caller":"traceutil/trace.go:171","msg":"trace[1327429890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"537.624991ms","start":"2026-08-06T19:11:47.999045Z","end":"2026-08-06T19:11:48.53667Z","steps":["trace[1327429890] 'agreement among raft nodes before linearized reading' (duration: 537.465367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:48.536698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.202735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T19:11:48.536731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:47.999032Z","time spent":"537.691853ms","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T19:11:48.536753Z","caller":"traceutil/trace.go:171","msg":"trace[393624562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"131.304548ms","start":"2026-08-06T19:11:48.405433Z","end":"2026-08-06T19:11:48.536738Z","steps":["trace[393624562] 'agreement among raft nodes before linearized reading' (duration: 131.098893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:48.536775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:47.792649Z","time spent":"744.041714ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:05.212848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.780503ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-06T19:12:05.212991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.661936Z","time spent":"551.049047ms","remote":"127.0.0.1:43880","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-06T19:12:05.227978Z","caller":"traceutil/trace.go:171","msg":"trace[1915022646] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1386; }","duration":"560.352278ms","start":"2026-08-06T19:12:04.667602Z","end":"2026-08-06T19:12:05.227954Z","steps":["trace[1915022646] 'read index received' (duration: 233.445568ms)","trace[1915022646] 'applied index is now lower than readState.Index' (duration: 326.90526ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:05.228115Z","caller":"traceutil/trace.go:171","msg":"trace[42607786] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"563.069146ms","start":"2026-08-06T19:12:04.66502Z","end":"2026-08-06T19:12:05.228089Z","steps":["trace[42607786] 'process raft request' (duration: 562.886631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.22826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.664996Z","time spent":"563.185019ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:05.228397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.771199ms","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-08-06T19:12:05.228437Z","caller":"traceutil/trace.go:171","msg":"trace[1682852591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"560.837262ms","start":"2026-08-06T19:12:04.667587Z","end":"2026-08-06T19:12:05.228424Z","steps":["trace[1682852591] 'agreement among raft nodes before linearized reading' (duration: 560.666057ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:05.22847Z","caller":"traceutil/trace.go:171","msg":"trace[53341298] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"563.814545ms","start":"2026-08-06T19:12:04.664643Z","end":"2026-08-06T19:12:05.228458Z","steps":["trace[53341298] 'process raft request' (duration: 563.072256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.228541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.664629Z","time spent":"563.869436ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:05.228473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.667579Z","time spent":"560.885873ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T19:12:05.23897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.978213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T19:12:05.239002Z","caller":"traceutil/trace.go:171","msg":"trace[471145407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"571.023754ms","start":"2026-08-06T19:12:04.667971Z","end":"2026-08-06T19:12:05.238994Z","steps":["trace[471145407] 'agreement among raft nodes before linearized reading' (duration: 570.895731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.239023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.667965Z","time spent":"571.054034ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T19:12:05.239395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.435984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T19:12:05.239425Z","caller":"traceutil/trace.go:171","msg":"trace[1424068564] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1336; }","duration":"208.490165ms","start":"2026-08-06T19:12:05.030927Z","end":"2026-08-06T19:12:05.239417Z","steps":["trace[1424068564] 'agreement among raft nodes before linearized reading' (duration: 208.381072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.23943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.393941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:12:05.239464Z","caller":"traceutil/trace.go:171","msg":"trace[1701105495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"240.475133ms","start":"2026-08-06T19:12:04.998971Z","end":"2026-08-06T19:12:05.239446Z","steps":["trace[1701105495] 'agreement among raft nodes before linearized reading' (duration: 240.445522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.239701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.803566ms","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-08-06T19:12:05.239728Z","caller":"traceutil/trace.go:171","msg":"trace[1564440065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"549.850847ms","start":"2026-08-06T19:12:04.689871Z","end":"2026-08-06T19:12:05.239721Z","steps":["trace[1564440065] 'agreement among raft nodes before linearized reading' (duration: 549.779815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.23977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.689861Z","time spent":"549.903269ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T19:12:05.239395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.822609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:12:05.24012Z","caller":"traceutil/trace.go:171","msg":"trace[1466465123] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1336; }","duration":"440.574708ms","start":"2026-08-06T19:12:04.799535Z","end":"2026-08-06T19:12:05.24011Z","steps":["trace[1466465123] 'agreement among raft nodes before linearized reading' (duration: 439.81784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:05.240147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:04.799525Z","time spent":"440.613939ms","remote":"127.0.0.1:40628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-06T19:12:05.875842Z","caller":"traceutil/trace.go:171","msg":"trace[1321470616] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"106.068998ms","start":"2026-08-06T19:12:05.769751Z","end":"2026-08-06T19:12:05.87582Z","steps":["trace[1321470616] 'process raft request' (duration: 105.928324ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:06.835497Z","caller":"traceutil/trace.go:171","msg":"trace[1503528260] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"170.041004ms","start":"2026-08-06T19:12:06.665429Z","end":"2026-08-06T19:12:06.83547Z","steps":["trace[1503528260] 'process raft request' (duration: 169.813888ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:07.340491Z","caller":"traceutil/trace.go:171","msg":"trace[1916738694] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"105.450833ms","start":"2026-08-06T19:12:07.235015Z","end":"2026-08-06T19:12:07.340466Z","steps":["trace[1916738694] 'process raft request' (duration: 105.284589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:07.938836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.24643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:12:07.938978Z","caller":"traceutil/trace.go:171","msg":"trace[968502811] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"703.712247ms","start":"2026-08-06T19:12:07.235232Z","end":"2026-08-06T19:12:07.938944Z","steps":["trace[968502811] 'process raft request' (duration: 239.281232ms)","trace[968502811] 'compare' (duration: 464.149858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:12:07.9391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:07.235219Z","time spent":"703.81521ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:07.939654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.739288ms","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-08-06T19:12:07.939689Z","caller":"traceutil/trace.go:171","msg":"trace[150970925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1343; }","duration":"677.8003ms","start":"2026-08-06T19:12:07.261881Z","end":"2026-08-06T19:12:07.939681Z","steps":["trace[150970925] 'agreement among raft nodes before linearized reading' (duration: 677.686187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:07.93971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:07.261869Z","time spent":"677.83722ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T19:12:07.938931Z","caller":"traceutil/trace.go:171","msg":"trace[1053670667] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1395; }","duration":"677.00208ms","start":"2026-08-06T19:12:07.261912Z","end":"2026-08-06T19:12:07.938914Z","steps":["trace[1053670667] 'read index received' (duration: 78.444708ms)","trace[1053670667] 'applied index is now lower than readState.Index' (duration: 598.555812ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:12:07.940439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.089769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:12:07.940478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.505703ms","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-08-06T19:12:07.94049Z","caller":"traceutil/trace.go:171","msg":"trace[1795638197] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1343; }","duration":"648.172721ms","start":"2026-08-06T19:12:07.292304Z","end":"2026-08-06T19:12:07.940477Z","steps":["trace[1795638197] 'agreement among raft nodes before linearized reading' (duration: 648.080309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:07.940489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.544204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T19:12:07.940512Z","caller":"traceutil/trace.go:171","msg":"trace[1253220500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1343; }","duration":"671.554095ms","start":"2026-08-06T19:12:07.26895Z","end":"2026-08-06T19:12:07.940504Z","steps":["trace[1253220500] 'agreement among raft nodes before linearized reading' (duration: 671.469863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:07.940524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:07.292294Z","time spent":"648.218582ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:12:07.940538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:07.268944Z","time spent":"671.589546ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T19:12:07.940538Z","caller":"traceutil/trace.go:171","msg":"trace[1249691485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1343; }","duration":"671.613765ms","start":"2026-08-06T19:12:07.26891Z","end":"2026-08-06T19:12:07.940524Z","steps":["trace[1249691485] 'agreement among raft nodes before linearized reading' (duration: 671.433821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:07.940577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:07.2689Z","time spent":"671.669118ms","remote":"127.0.0.1:44162","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-08-06T19:12:08.200113Z","caller":"traceutil/trace.go:171","msg":"trace[1346546353] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"256.258287ms","start":"2026-08-06T19:12:07.943832Z","end":"2026-08-06T19:12:08.20009Z","steps":["trace[1346546353] 'process raft request' (duration: 255.883168ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:08.200225Z","caller":"traceutil/trace.go:171","msg":"trace[1077869883] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1398; }","duration":"201.198332ms","start":"2026-08-06T19:12:07.999004Z","end":"2026-08-06T19:12:08.200203Z","steps":["trace[1077869883] 'read index received' (duration: 200.768761ms)","trace[1077869883] 'applied index is now lower than readState.Index' (duration: 428.371µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:08.200458Z","caller":"traceutil/trace.go:171","msg":"trace[1183422956] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"256.36634ms","start":"2026-08-06T19:12:07.944076Z","end":"2026-08-06T19:12:08.200442Z","steps":["trace[1183422956] 'process raft request' (duration: 255.882048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:08.200574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.564752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:12:08.200642Z","caller":"traceutil/trace.go:171","msg":"trace[1475524159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"201.653924ms","start":"2026-08-06T19:12:07.998948Z","end":"2026-08-06T19:12:08.200602Z","steps":["trace[1475524159] 'agreement among raft nodes before linearized reading' (duration: 201.388098ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:17.847248Z","caller":"traceutil/trace.go:171","msg":"trace[535596848] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"304.779909ms","start":"2026-08-06T19:12:17.542449Z","end":"2026-08-06T19:12:17.847229Z","steps":["trace[535596848] 'process raft request' (duration: 304.650996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:17.847374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:17.542424Z","time spent":"304.890012ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:18.53098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996595464428614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T19:12:18.948312Z","caller":"traceutil/trace.go:171","msg":"trace[155511863] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"917.813421ms","start":"2026-08-06T19:12:18.030472Z","end":"2026-08-06T19:12:18.948285Z","steps":["trace[155511863] 'read index received' (duration: 917.567065ms)","trace[155511863] 'applied index is now lower than readState.Index' (duration: 244.986µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:18.948439Z","caller":"traceutil/trace.go:171","msg":"trace[615944395] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"923.542004ms","start":"2026-08-06T19:12:18.02486Z","end":"2026-08-06T19:12:18.948402Z","steps":["trace[615944395] 'process raft request' (duration: 923.230286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.948649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.024834Z","time spent":"923.700948ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:18.948938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.464058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-06T19:12:18.949007Z","caller":"traceutil/trace.go:171","msg":"trace[622424083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1422; }","duration":"918.56438ms","start":"2026-08-06T19:12:18.030425Z","end":"2026-08-06T19:12:18.948989Z","steps":["trace[622424083] 'agreement among raft nodes before linearized reading' (duration: 917.998496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.949141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.030415Z","time spent":"918.713334ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T19:12:18.977615Z","caller":"traceutil/trace.go:171","msg":"trace[776700278] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"444.973498ms","start":"2026-08-06T19:12:18.532613Z","end":"2026-08-06T19:12:18.977587Z","steps":["trace[776700278] 'process raft request' (duration: 444.777213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.977785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.532591Z","time spent":"445.102222ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:18.978008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.02831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-08-06T19:12:18.978054Z","caller":"traceutil/trace.go:171","msg":"trace[963641262] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1424; }","duration":"725.103371ms","start":"2026-08-06T19:12:18.252939Z","end":"2026-08-06T19:12:18.978042Z","steps":["trace[963641262] 'agreement among raft nodes before linearized reading' (duration: 724.892146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.978048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.934258ms","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-08-06T19:12:18.978667Z","caller":"traceutil/trace.go:171","msg":"trace[981520133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1424; }","duration":"735.636135ms","start":"2026-08-06T19:12:18.243012Z","end":"2026-08-06T19:12:18.978648Z","steps":["trace[981520133] 'agreement among raft nodes before linearized reading' (duration: 734.748463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.978592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.520003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T19:12:18.978088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.252928Z","time spent":"725.152352ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-06T19:12:18.978918Z","caller":"traceutil/trace.go:171","msg":"trace[358597829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1424; }","duration":"544.940943ms","start":"2026-08-06T19:12:18.433915Z","end":"2026-08-06T19:12:18.978856Z","steps":["trace[358597829] 'agreement among raft nodes before linearized reading' (duration: 543.885677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.978126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.54926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:12:18.97902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.433887Z","time spent":"545.113258ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:12:18.978727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.242999Z","time spent":"735.713447ms","remote":"127.0.0.1:44162","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-08-06T19:12:18.979027Z","caller":"traceutil/trace.go:171","msg":"trace[1744796895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1424; }","duration":"653.455002ms","start":"2026-08-06T19:12:18.325549Z","end":"2026-08-06T19:12:18.979004Z","steps":["trace[1744796895] 'agreement among raft nodes before linearized reading' (duration: 652.55728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.979228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.325538Z","time spent":"653.675548ms","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T19:12:18.978234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.339937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T19:12:18.979387Z","caller":"traceutil/trace.go:171","msg":"trace[1360419625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1424; }","duration":"734.518886ms","start":"2026-08-06T19:12:18.244857Z","end":"2026-08-06T19:12:18.979375Z","steps":["trace[1360419625] 'agreement among raft nodes before linearized reading' (duration: 733.298276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.979427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:18.24484Z","time spent":"734.574408ms","remote":"127.0.0.1:44162","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-08-06T19:12:18.978445Z","caller":"traceutil/trace.go:171","msg":"trace[110145299] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"103.118295ms","start":"2026-08-06T19:12:18.874654Z","end":"2026-08-06T19:12:18.977772Z","steps":["trace[110145299] 'process raft request' (duration: 102.876949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:18.978236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.077087ms","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:532"} {"level":"info","ts":"2026-08-06T19:12:18.979631Z","caller":"traceutil/trace.go:171","msg":"trace[718259120] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1424; }","duration":"290.481641ms","start":"2026-08-06T19:12:18.68914Z","end":"2026-08-06T19:12:18.979621Z","steps":["trace[718259120] 'agreement among raft nodes before linearized reading' (duration: 289.015305ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:19.139303Z","caller":"traceutil/trace.go:171","msg":"trace[624694415] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"185.617864ms","start":"2026-08-06T19:12:18.953537Z","end":"2026-08-06T19:12:19.139155Z","steps":["trace[624694415] 'process raft request' (duration: 185.342407ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:19.139655Z","caller":"traceutil/trace.go:171","msg":"trace[1975313982] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"155.44816ms","start":"2026-08-06T19:12:18.984173Z","end":"2026-08-06T19:12:19.139621Z","steps":["trace[1975313982] 'process raft request' (duration: 155.176034ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:19.139652Z","caller":"traceutil/trace.go:171","msg":"trace[1436294013] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"155.40545ms","start":"2026-08-06T19:12:18.984226Z","end":"2026-08-06T19:12:19.139631Z","steps":["trace[1436294013] 'process raft request' (duration: 155.282017ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:19.140256Z","caller":"traceutil/trace.go:171","msg":"trace[464008962] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"155.840191ms","start":"2026-08-06T19:12:18.984227Z","end":"2026-08-06T19:12:19.140068Z","steps":["trace[464008962] 'process raft request' (duration: 155.340568ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:19.14076Z","caller":"traceutil/trace.go:171","msg":"trace[1358407182] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1480; }","duration":"144.652732ms","start":"2026-08-06T19:12:18.99608Z","end":"2026-08-06T19:12:19.140733Z","steps":["trace[1358407182] 'read index received' (duration: 142.657832ms)","trace[1358407182] 'applied index is now lower than readState.Index' (duration: 1.99241ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:19.140824Z","caller":"traceutil/trace.go:171","msg":"trace[1853120913] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"154.954138ms","start":"2026-08-06T19:12:18.985847Z","end":"2026-08-06T19:12:19.140801Z","steps":["trace[1853120913] 'process raft request' (duration: 154.143778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:19.141076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.780304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:12:19.141136Z","caller":"traceutil/trace.go:171","msg":"trace[848221175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1429; }","duration":"145.075332ms","start":"2026-08-06T19:12:18.996045Z","end":"2026-08-06T19:12:19.14112Z","steps":["trace[848221175] 'agreement among raft nodes before linearized reading' (duration: 144.770925ms)"],"step_count":1}