{"level":"warn","ts":"2026-07-02T00:22:06.107598Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:22:06.107717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-07-02T00:22:06.107811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:22:06.107826Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-02T00:22:06.107861Z","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-07-02T00:22:06.108726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-07-02T00:22:06.108862Z","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.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-07-02T00:22:06.115029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.873841ms"} {"level":"info","ts":"2026-07-02T00:22:06.122491Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-07-02T00:22:06.122561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T00:22:06.122585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-07-02T00:22:06.122598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T00:22:06.122605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-07-02T00:22:06.122643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-07-02T00:22:06.128233Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T00:22:06.129711Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T00:22:06.130934Z","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-07-02T00:22:06.133752Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T00:22:06.134015Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T00:22:06.1376Z","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-07-02T00:22:06.137747Z","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-07-02T00:22:06.137764Z","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-07-02T00:22:06.138763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-07-02T00:22:06.139756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-02T00:22:06.142452Z","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-07-02T00:22:06.142784Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T00:22:06.142839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T00:22:06.143015Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-02T00:22:06.143035Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-02T00:22:06.723198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T00:22:06.723281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T00:22:06.723312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-07-02T00:22:06.723341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-07-02T00:22:06.723355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-02T00:22:06.723397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-07-02T00:22:06.723421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-02T00:22:06.725529Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T00:22:06.72813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:22:06.728183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:22:06.728633Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T00:22:06.728698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T00:22:06.728568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:06.729955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-07-02T00:22:06.730621Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:06.730761Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:06.730806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:06.730829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-02T00:22:06.986549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.092699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:06.986668Z","caller":"traceutil/trace.go:171","msg":"trace[723822877] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"130.265654ms","start":"2026-07-02T00:22:06.856375Z","end":"2026-07-02T00:22:06.98664Z","steps":["trace[723822877] 'count revisions from in-memory index tree' (duration: 129.958516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:06.986429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.882552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:06.987337Z","caller":"traceutil/trace.go:171","msg":"trace[1844165097] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"101.834636ms","start":"2026-07-02T00:22:06.885487Z","end":"2026-07-02T00:22:06.987321Z","steps":["trace[1844165097] 'count revisions from in-memory index tree' (duration: 100.835061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:06.986429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.23347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:06.98759Z","caller":"traceutil/trace.go:171","msg":"trace[103583208] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"112.43191ms","start":"2026-07-02T00:22:06.875142Z","end":"2026-07-02T00:22:06.987574Z","steps":["trace[103583208] 'count revisions from in-memory index tree' (duration: 111.104027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:06.986515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.326282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:06.987733Z","caller":"traceutil/trace.go:171","msg":"trace[1603977695] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"112.537204ms","start":"2026-07-02T00:22:06.875181Z","end":"2026-07-02T00:22:06.987718Z","steps":["trace[1603977695] 'range keys from in-memory index tree' (duration: 111.206949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:31.641635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.053561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:22:31.641638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.607672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:22:31.641721Z","caller":"traceutil/trace.go:171","msg":"trace[1184197690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"463.217714ms","start":"2026-07-02T00:22:31.178486Z","end":"2026-07-02T00:22:31.641704Z","steps":["trace[1184197690] 'range keys from in-memory index tree' (duration: 462.942897ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:31.641756Z","caller":"traceutil/trace.go:171","msg":"trace[1661138154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"135.765365ms","start":"2026-07-02T00:22:31.505972Z","end":"2026-07-02T00:22:31.641738Z","steps":["trace[1661138154] 'range keys from in-memory index tree' (duration: 135.51583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:31.641761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:31.178451Z","time spent":"463.302176ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:22:37.879995Z","caller":"traceutil/trace.go:171","msg":"trace[405441840] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"119.263297ms","start":"2026-07-02T00:22:37.760704Z","end":"2026-07-02T00:22:37.879967Z","steps":["trace[405441840] 'process raft request' (duration: 119.129174ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.011473Z","caller":"traceutil/trace.go:171","msg":"trace[352598896] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"130.030607ms","start":"2026-07-02T00:22:44.881427Z","end":"2026-07-02T00:22:45.011458Z","steps":["trace[352598896] 'process raft request' (duration: 129.946035ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025227Z","caller":"traceutil/trace.go:171","msg":"trace[1444494313] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"140.287363ms","start":"2026-07-02T00:22:44.884927Z","end":"2026-07-02T00:22:45.025214Z","steps":["trace[1444494313] 'process raft request' (duration: 139.739238ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025328Z","caller":"traceutil/trace.go:171","msg":"trace[551022451] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"137.427709ms","start":"2026-07-02T00:22:44.887896Z","end":"2026-07-02T00:22:45.025324Z","steps":["trace[551022451] 'process raft request' (duration: 136.834924ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025387Z","caller":"traceutil/trace.go:171","msg":"trace[99395605] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"136.214678ms","start":"2026-07-02T00:22:44.889168Z","end":"2026-07-02T00:22:45.025382Z","steps":["trace[99395605] 'process raft request' (duration: 135.588572ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025442Z","caller":"traceutil/trace.go:171","msg":"trace[359965522] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"135.320823ms","start":"2026-07-02T00:22:44.890116Z","end":"2026-07-02T00:22:45.025437Z","steps":["trace[359965522] 'process raft request' (duration: 134.697107ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025497Z","caller":"traceutil/trace.go:171","msg":"trace[1264204569] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"129.716709ms","start":"2026-07-02T00:22:44.895777Z","end":"2026-07-02T00:22:45.025493Z","steps":["trace[1264204569] 'process raft request' (duration: 129.061002ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025557Z","caller":"traceutil/trace.go:171","msg":"trace[381074477] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"125.040058ms","start":"2026-07-02T00:22:44.900512Z","end":"2026-07-02T00:22:45.025552Z","steps":["trace[381074477] 'process raft request' (duration: 124.34722ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025617Z","caller":"traceutil/trace.go:171","msg":"trace[31128873] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"124.906653ms","start":"2026-07-02T00:22:44.900705Z","end":"2026-07-02T00:22:45.025612Z","steps":["trace[31128873] 'process raft request' (duration: 124.179455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025676Z","caller":"traceutil/trace.go:171","msg":"trace[139798904] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"123.070057ms","start":"2026-07-02T00:22:44.902597Z","end":"2026-07-02T00:22:45.025667Z","steps":["trace[139798904] 'process raft request' (duration: 122.312417ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025735Z","caller":"traceutil/trace.go:171","msg":"trace[127839237] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"115.081509ms","start":"2026-07-02T00:22:44.910647Z","end":"2026-07-02T00:22:45.025729Z","steps":["trace[127839237] 'process raft request' (duration: 114.288599ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.025793Z","caller":"traceutil/trace.go:171","msg":"trace[1794663965] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"100.269106ms","start":"2026-07-02T00:22:44.925518Z","end":"2026-07-02T00:22:45.025787Z","steps":["trace[1794663965] 'process raft request' (duration: 99.446675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:45.293134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.772594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:22:45.293246Z","caller":"traceutil/trace.go:171","msg":"trace[152719823] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"141.383241ms","start":"2026-07-02T00:22:45.151846Z","end":"2026-07-02T00:22:45.293229Z","steps":["trace[152719823] 'compare' (duration: 133.61781ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.358406Z","caller":"traceutil/trace.go:171","msg":"trace[1150506945] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"206.208389ms","start":"2026-07-02T00:22:45.152183Z","end":"2026-07-02T00:22:45.358391Z","steps":["trace[1150506945] 'process raft request' (duration: 206.104746ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.366245Z","caller":"traceutil/trace.go:171","msg":"trace[130703226] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"196.172961ms","start":"2026-07-02T00:22:45.170046Z","end":"2026-07-02T00:22:45.366219Z","steps":["trace[130703226] 'process raft request' (duration: 195.989205ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.371473Z","caller":"traceutil/trace.go:171","msg":"trace[1004840066] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"193.629673ms","start":"2026-07-02T00:22:45.177832Z","end":"2026-07-02T00:22:45.371462Z","steps":["trace[1004840066] 'read index received' (duration: 180.7655ms)","trace[1004840066] 'applied index is now lower than readState.Index' (duration: 12.863323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:45.371541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.701685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:45.37156Z","caller":"traceutil/trace.go:171","msg":"trace[394030155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"193.757007ms","start":"2026-07-02T00:22:45.177796Z","end":"2026-07-02T00:22:45.371553Z","steps":["trace[394030155] 'agreement among raft nodes before linearized reading' (duration: 193.707806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.371954Z","caller":"traceutil/trace.go:171","msg":"trace[1306033827] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"185.371639ms","start":"2026-07-02T00:22:45.186575Z","end":"2026-07-02T00:22:45.371946Z","steps":["trace[1306033827] 'process raft request' (duration: 184.652611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:45.870932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.786727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:22:45.87112Z","caller":"traceutil/trace.go:171","msg":"trace[732118188] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"160.132416ms","start":"2026-07-02T00:22:45.710975Z","end":"2026-07-02T00:22:45.871108Z","steps":["trace[732118188] 'process raft request' (duration: 160.046044ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:45.871237Z","caller":"traceutil/trace.go:171","msg":"trace[133320521] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"170.196346ms","start":"2026-07-02T00:22:45.701033Z","end":"2026-07-02T00:22:45.871229Z","steps":["trace[133320521] 'process raft request' (duration: 61.057841ms)","trace[133320521] 'compare' (duration: 108.661473ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:51.639489Z","caller":"traceutil/trace.go:171","msg":"trace[114956477] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"159.362546ms","start":"2026-07-02T00:22:51.480097Z","end":"2026-07-02T00:22:51.63946Z","steps":["trace[114956477] 'process raft request' (duration: 158.75496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.698185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6tjn4\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-02T00:22:52.085065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.526957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:22:52.085137Z","caller":"traceutil/trace.go:171","msg":"trace[876393264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"138.65026ms","start":"2026-07-02T00:22:51.94647Z","end":"2026-07-02T00:22:52.085121Z","steps":["trace[876393264] 'range keys from in-memory index tree' (duration: 138.303442ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:52.085105Z","caller":"traceutil/trace.go:171","msg":"trace[363580408] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6tjn4; range_end:; response_count:1; response_revision:630; }","duration":"186.847298ms","start":"2026-07-02T00:22:51.898242Z","end":"2026-07-02T00:22:52.085089Z","steps":["trace[363580408] 'range keys from in-memory index tree' (duration: 186.514949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:52.329327Z","caller":"traceutil/trace.go:171","msg":"trace[17852945] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"231.883654ms","start":"2026-07-02T00:22:52.09738Z","end":"2026-07-02T00:22:52.329264Z","steps":["trace[17852945] 'process raft request' (duration: 231.438653ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:52.337016Z","caller":"traceutil/trace.go:171","msg":"trace[173530250] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"160.454205ms","start":"2026-07-02T00:22:52.176516Z","end":"2026-07-02T00:22:52.33697Z","steps":["trace[173530250] 'read index received' (duration: 152.360025ms)","trace[173530250] 'applied index is now lower than readState.Index' (duration: 8.09327ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:52.337096Z","caller":"traceutil/trace.go:171","msg":"trace[147003022] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"219.649028ms","start":"2026-07-02T00:22:52.11742Z","end":"2026-07-02T00:22:52.337069Z","steps":["trace[147003022] 'process raft request' (duration: 212.306527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.337215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.67179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:52.337258Z","caller":"traceutil/trace.go:171","msg":"trace[165513619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"160.783473ms","start":"2026-07-02T00:22:52.176467Z","end":"2026-07-02T00:22:52.337251Z","steps":["trace[165513619] 'agreement among raft nodes before linearized reading' (duration: 160.66626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.454274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.576699767s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-02T00:22:54.478238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77414446s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:22:54.478383Z","caller":"traceutil/trace.go:171","msg":"trace[1771050731] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.600899573s","start":"2026-07-02T00:22:52.877472Z","end":"2026-07-02T00:22:54.478371Z","steps":["trace[1771050731] 'process raft request' (duration: 1.600846082s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:54.478369Z","caller":"traceutil/trace.go:171","msg":"trace[175491786] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.089517034s","start":"2026-07-02T00:22:52.388828Z","end":"2026-07-02T00:22:54.478345Z","steps":["trace[175491786] 'process raft request' (duration: 315.197381ms)","trace[175491786] 'compare' (duration: 1.773935654s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:54.478433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:52.877444Z","time spent":"1.600960565s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:22:54.478441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:52.388804Z","time spent":"2.089606096s","remote":"127.0.0.1:55222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:54.478432Z","caller":"traceutil/trace.go:171","msg":"trace[1266376247] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.912274635s","start":"2026-07-02T00:22:52.566104Z","end":"2026-07-02T00:22:54.478379Z","steps":["trace[1266376247] 'read index received' (duration: 137.858779ms)","trace[1266376247] 'applied index is now lower than readState.Index' (duration: 1.774411726s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:54.478708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301077091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:54.478734Z","caller":"traceutil/trace.go:171","msg":"trace[846918461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.301155923s","start":"2026-07-02T00:22:53.177571Z","end":"2026-07-02T00:22:54.478727Z","steps":["trace[846918461] 'agreement among raft nodes before linearized reading' (duration: 1.301117572s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.478756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:53.177536Z","time spent":"1.301214734s","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:22:54.478771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912642506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T00:22:54.478755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770967928s","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-07-02T00:22:54.478818Z","caller":"traceutil/trace.go:171","msg":"trace[1450537620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.912705987s","start":"2026-07-02T00:22:52.566096Z","end":"2026-07-02T00:22:54.478802Z","steps":["trace[1450537620] 'agreement among raft nodes before linearized reading' (duration: 1.912468782s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:54.47883Z","caller":"traceutil/trace.go:171","msg":"trace[620120973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"1.771091181s","start":"2026-07-02T00:22:52.70773Z","end":"2026-07-02T00:22:54.478822Z","steps":["trace[620120973] 'agreement among raft nodes before linearized reading' (duration: 1.770922707s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.478855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:52.707712Z","time spent":"1.771136092s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:22:54.478863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:52.566039Z","time spent":"1.9128144s","remote":"127.0.0.1:55174","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-07-02T00:22:54.478884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.458216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:22:54.478904Z","caller":"traceutil/trace.go:171","msg":"trace[969986784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"313.515388ms","start":"2026-07-02T00:22:54.165383Z","end":"2026-07-02T00:22:54.478898Z","steps":["trace[969986784] 'agreement among raft nodes before linearized reading' (duration: 313.454556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.478878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409520288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T00:22:54.478924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:54.165362Z","time spent":"313.557989ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T00:22:54.478947Z","caller":"traceutil/trace.go:171","msg":"trace[86232736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.40959698s","start":"2026-07-02T00:22:53.069335Z","end":"2026-07-02T00:22:54.478932Z","steps":["trace[86232736] 'agreement among raft nodes before linearized reading' (duration: 1.409415645s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.478978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:53.069249Z","time spent":"1.409723494s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T00:22:54.634272Z","caller":"traceutil/trace.go:171","msg":"trace[1496876384] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"148.487995ms","start":"2026-07-02T00:22:54.485753Z","end":"2026-07-02T00:22:54.634241Z","steps":["trace[1496876384] 'process raft request' (duration: 148.356842ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:54.634518Z","caller":"traceutil/trace.go:171","msg":"trace[980063873] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"145.791975ms","start":"2026-07-02T00:22:54.488695Z","end":"2026-07-02T00:22:54.634486Z","steps":["trace[980063873] 'read index received' (duration: 107.575996ms)","trace[980063873] 'applied index is now lower than readState.Index' (duration: 38.214719ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:54.634566Z","caller":"traceutil/trace.go:171","msg":"trace[1996677498] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"149.734358ms","start":"2026-07-02T00:22:54.484802Z","end":"2026-07-02T00:22:54.634537Z","steps":["trace[1996677498] 'process raft request' (duration: 111.392996ms)","trace[1996677498] 'compare' (duration: 37.752496ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:54.634761Z","caller":"traceutil/trace.go:171","msg":"trace[1942995267] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"148.902966ms","start":"2026-07-02T00:22:54.485844Z","end":"2026-07-02T00:22:54.634746Z","steps":["trace[1942995267] 'process raft request' (duration: 148.347771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:54.634784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.195834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:22:54.634797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.088163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:54.634841Z","caller":"traceutil/trace.go:171","msg":"trace[1003150499] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:639; }","duration":"144.277516ms","start":"2026-07-02T00:22:54.490547Z","end":"2026-07-02T00:22:54.634824Z","steps":["trace[1003150499] 'agreement among raft nodes before linearized reading' (duration: 144.171403ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:54.634886Z","caller":"traceutil/trace.go:171","msg":"trace[1863537128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"146.214037ms","start":"2026-07-02T00:22:54.488651Z","end":"2026-07-02T00:22:54.634865Z","steps":["trace[1863537128] 'agreement among raft nodes before linearized reading' (duration: 146.024291ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:55.134956Z","caller":"traceutil/trace.go:171","msg":"trace[1987786062] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"101.061087ms","start":"2026-07-02T00:22:55.033873Z","end":"2026-07-02T00:22:55.134934Z","steps":["trace[1987786062] 'process raft request' (duration: 99.605899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:55.555328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.161215ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:55.555519Z","caller":"traceutil/trace.go:171","msg":"trace[1380572806] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:642; }","duration":"265.393172ms","start":"2026-07-02T00:22:55.290101Z","end":"2026-07-02T00:22:55.555494Z","steps":["trace[1380572806] 'range keys from in-memory index tree' (duration: 265.132694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:55.613982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.111234ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T00:22:55.614336Z","caller":"traceutil/trace.go:171","msg":"trace[1855028489] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"416.018072ms","start":"2026-07-02T00:22:55.198298Z","end":"2026-07-02T00:22:55.614316Z","steps":["trace[1855028489] 'process raft request' (duration: 415.829687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:55.61444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:55.198266Z","time spent":"416.116955ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:56.143032Z","caller":"traceutil/trace.go:171","msg":"trace[2038286246] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"104.272481ms","start":"2026-07-02T00:22:56.038711Z","end":"2026-07-02T00:22:56.142984Z","steps":["trace[2038286246] 'process raft request' (duration: 103.419319ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:56.301909Z","caller":"traceutil/trace.go:171","msg":"trace[2114533260] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"102.575176ms","start":"2026-07-02T00:22:56.199306Z","end":"2026-07-02T00:22:56.301881Z","steps":["trace[2114533260] 'process raft request' (duration: 49.989835ms)","trace[2114533260] 'compare' (duration: 52.433778ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:00.289975Z","caller":"traceutil/trace.go:171","msg":"trace[1648095821] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"112.505023ms","start":"2026-07-02T00:23:00.17745Z","end":"2026-07-02T00:23:00.289955Z","steps":["trace[1648095821] 'read index received' (duration: 112.267367ms)","trace[1648095821] 'applied index is now lower than readState.Index' (duration: 236.556µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:00.290448Z","caller":"traceutil/trace.go:171","msg":"trace[1076047075] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"206.853975ms","start":"2026-07-02T00:23:00.083537Z","end":"2026-07-02T00:23:00.290391Z","steps":["trace[1076047075] 'process raft request' (duration: 206.28194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:00.290614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.147579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:00.290744Z","caller":"traceutil/trace.go:171","msg":"trace[1848237907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"113.304224ms","start":"2026-07-02T00:23:00.177425Z","end":"2026-07-02T00:23:00.290729Z","steps":["trace[1848237907] 'agreement among raft nodes before linearized reading' (duration: 113.15285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:10.678371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919580875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:23:11.17561Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.165625745s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:23:11.17601Z","caller":"traceutil/trace.go:171","msg":"trace[81381978] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.166075476s","start":"2026-07-02T00:23:10.009918Z","end":"2026-07-02T00:23:11.175994Z","steps":["trace[81381978] 'process raft request' (duration: 1.165978784s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.176132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.009908Z","time spent":"1.166141818s","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:23:11.17837Z","caller":"traceutil/trace.go:171","msg":"trace[1259626635] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"1.000903875s","start":"2026-07-02T00:23:10.17745Z","end":"2026-07-02T00:23:11.178354Z","steps":["trace[1259626635] 'read index received' (duration: 998.431772ms)","trace[1259626635] 'applied index is now lower than readState.Index' (duration: 2.471193ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:11.178486Z","caller":"traceutil/trace.go:171","msg":"trace[246196160] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.124957138s","start":"2026-07-02T00:23:10.053519Z","end":"2026-07-02T00:23:11.178476Z","steps":["trace[246196160] 'process raft request' (duration: 1.124719212s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.178567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.053494Z","time spent":"1.125024769s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:11.178776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.893114ms","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-07-02T00:23:11.178861Z","caller":"traceutil/trace.go:171","msg":"trace[552448061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"618.017677ms","start":"2026-07-02T00:23:10.560823Z","end":"2026-07-02T00:23:11.17884Z","steps":["trace[552448061] 'agreement among raft nodes before linearized reading' (duration: 617.821472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.178915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.560805Z","time spent":"618.098399ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:23:11.178988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.676931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:23:11.179037Z","caller":"traceutil/trace.go:171","msg":"trace[742941873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"472.764963ms","start":"2026-07-02T00:23:10.706261Z","end":"2026-07-02T00:23:11.179026Z","steps":["trace[742941873] 'agreement among raft nodes before linearized reading' (duration: 472.627969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.179074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.70624Z","time spent":"472.826595ms","remote":"127.0.0.1:55174","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-07-02T00:23:11.179231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001780958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:11.179267Z","caller":"traceutil/trace.go:171","msg":"trace[1696553319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.001859169s","start":"2026-07-02T00:23:10.177399Z","end":"2026-07-02T00:23:11.179258Z","steps":["trace[1696553319] 'agreement among raft nodes before linearized reading' (duration: 1.001808298s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.179308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.177375Z","time spent":"1.001912801s","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:23:11.179646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.277822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:23:11.179704Z","caller":"traceutil/trace.go:171","msg":"trace[1014444213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"474.371075ms","start":"2026-07-02T00:23:10.705318Z","end":"2026-07-02T00:23:11.179689Z","steps":["trace[1014444213] 'agreement among raft nodes before linearized reading' (duration: 474.231502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.179749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.705298Z","time spent":"474.437827ms","remote":"127.0.0.1:55174","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-07-02T00:23:11.180527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.678963ms","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-07-02T00:23:11.180587Z","caller":"traceutil/trace.go:171","msg":"trace[899963288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"474.777895ms","start":"2026-07-02T00:23:10.705795Z","end":"2026-07-02T00:23:11.180573Z","steps":["trace[899963288] 'agreement among raft nodes before linearized reading' (duration: 473.319937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:11.180636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:10.705778Z","time spent":"474.845907ms","remote":"127.0.0.1:55174","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-07-02T00:23:18.840448Z","caller":"traceutil/trace.go:171","msg":"trace[1021215701] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"190.388135ms","start":"2026-07-02T00:23:18.650037Z","end":"2026-07-02T00:23:18.840425Z","steps":["trace[1021215701] 'process raft request' (duration: 190.340494ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.840936Z","caller":"traceutil/trace.go:171","msg":"trace[1928300629] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"292.712124ms","start":"2026-07-02T00:23:18.548201Z","end":"2026-07-02T00:23:18.840913Z","steps":["trace[1928300629] 'process raft request' (duration: 200.692084ms)","trace[1928300629] 'compare' (duration: 91.2606ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:18.841314Z","caller":"traceutil/trace.go:171","msg":"trace[546779010] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"282.435698ms","start":"2026-07-02T00:23:18.558859Z","end":"2026-07-02T00:23:18.841295Z","steps":["trace[546779010] 'process raft request' (duration: 281.414921ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.841513Z","caller":"traceutil/trace.go:171","msg":"trace[767305819] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:905; }","duration":"192.366417ms","start":"2026-07-02T00:23:18.649132Z","end":"2026-07-02T00:23:18.841499Z","steps":["trace[767305819] 'read index received' (duration: 99.771902ms)","trace[767305819] 'applied index is now lower than readState.Index' (duration: 92.593325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:18.841934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.779189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rtj6n\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-07-02T00:23:18.84199Z","caller":"traceutil/trace.go:171","msg":"trace[377186400] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rtj6n; range_end:; response_count:1; response_revision:878; }","duration":"192.872761ms","start":"2026-07-02T00:23:18.649103Z","end":"2026-07-02T00:23:18.841976Z","steps":["trace[377186400] 'agreement among raft nodes before linearized reading' (duration: 192.700896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:18.842199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.532155ms","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":"info","ts":"2026-07-02T00:23:18.842249Z","caller":"traceutil/trace.go:171","msg":"trace[1219436473] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:878; }","duration":"124.615038ms","start":"2026-07-02T00:23:18.717621Z","end":"2026-07-02T00:23:18.842236Z","steps":["trace[1219436473] 'agreement among raft nodes before linearized reading' (duration: 124.541316ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:19.000197Z","caller":"traceutil/trace.go:171","msg":"trace[463585481] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"149.212476ms","start":"2026-07-02T00:23:18.850956Z","end":"2026-07-02T00:23:19.000168Z","steps":["trace[463585481] 'process raft request' (duration: 124.608597ms)","trace[463585481] 'compare' (duration: 24.476175ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:19.165623Z","caller":"traceutil/trace.go:171","msg":"trace[1470325025] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"104.530516ms","start":"2026-07-02T00:23:19.061069Z","end":"2026-07-02T00:23:19.1656Z","steps":["trace[1470325025] 'read index received' (duration: 55.511453ms)","trace[1470325025] 'applied index is now lower than readState.Index' (duration: 49.018083ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:19.165774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.68947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-02T00:23:19.165808Z","caller":"traceutil/trace.go:171","msg":"trace[656434812] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:881; }","duration":"104.775952ms","start":"2026-07-02T00:23:19.061022Z","end":"2026-07-02T00:23:19.165798Z","steps":["trace[656434812] 'agreement among raft nodes before linearized reading' (duration: 104.66848ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:19.166109Z","caller":"traceutil/trace.go:171","msg":"trace[1445000233] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"155.334446ms","start":"2026-07-02T00:23:19.010759Z","end":"2026-07-02T00:23:19.166093Z","steps":["trace[1445000233] 'process raft request' (duration: 105.792079ms)","trace[1445000233] 'compare' (duration: 48.945872ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:19.343414Z","caller":"traceutil/trace.go:171","msg":"trace[1819636183] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"102.187765ms","start":"2026-07-02T00:23:19.241212Z","end":"2026-07-02T00:23:19.3434Z","steps":["trace[1819636183] 'process raft request' (duration: 38.911181ms)","trace[1819636183] 'compare' (duration: 62.901293ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:19.343591Z","caller":"traceutil/trace.go:171","msg":"trace[1269092980] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"101.342163ms","start":"2026-07-02T00:23:19.242243Z","end":"2026-07-02T00:23:19.343585Z","steps":["trace[1269092980] 'process raft request' (duration: 100.924233ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:19.343652Z","caller":"traceutil/trace.go:171","msg":"trace[1283764990] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"100.372518ms","start":"2026-07-02T00:23:19.243275Z","end":"2026-07-02T00:23:19.343647Z","steps":["trace[1283764990] 'process raft request' (duration: 99.926327ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:46.198216Z","caller":"traceutil/trace.go:171","msg":"trace[1119606805] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"384.659705ms","start":"2026-07-02T00:23:45.813531Z","end":"2026-07-02T00:23:46.198191Z","steps":["trace[1119606805] 'process raft request' (duration: 384.494981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:46.198351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:45.813505Z","time spent":"384.777907ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:09.580991Z","caller":"traceutil/trace.go:171","msg":"trace[30175231] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"101.808035ms","start":"2026-07-02T00:24:09.47915Z","end":"2026-07-02T00:24:09.580958Z","steps":["trace[30175231] 'process raft request' (duration: 101.63112ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:09.899997Z","caller":"traceutil/trace.go:171","msg":"trace[270439024] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"301.613386ms","start":"2026-07-02T00:24:09.598349Z","end":"2026-07-02T00:24:09.899962Z","steps":["trace[270439024] 'read index received' (duration: 301.318878ms)","trace[270439024] 'applied index is now lower than readState.Index' (duration: 293.378µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:09.90063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.252784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T00:24:09.900746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.101932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:24:09.900759Z","caller":"traceutil/trace.go:171","msg":"trace[500666875] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"302.969812ms","start":"2026-07-02T00:24:09.597742Z","end":"2026-07-02T00:24:09.900712Z","steps":["trace[500666875] 'process raft request' (duration: 302.029138ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:09.900807Z","caller":"traceutil/trace.go:171","msg":"trace[634568565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"289.206194ms","start":"2026-07-02T00:24:09.61158Z","end":"2026-07-02T00:24:09.900787Z","steps":["trace[634568565] 'agreement among raft nodes before linearized reading' (duration: 288.989648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.900939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.597718Z","time spent":"303.128816ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:09.900931Z","caller":"traceutil/trace.go:171","msg":"trace[1817069924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"302.393547ms","start":"2026-07-02T00:24:09.598336Z","end":"2026-07-02T00:24:09.90073Z","steps":["trace[1817069924] 'agreement among raft nodes before linearized reading' (duration: 302.053228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.901413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.598296Z","time spent":"302.697915ms","remote":"127.0.0.1:55174","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-07-02T00:24:10.904134Z","caller":"traceutil/trace.go:171","msg":"trace[305367468] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"211.26755ms","start":"2026-07-02T00:24:10.692794Z","end":"2026-07-02T00:24:10.904061Z","steps":["trace[305367468] 'process raft request' (duration: 211.093195ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:26.390311Z","caller":"traceutil/trace.go:171","msg":"trace[183411488] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"105.453389ms","start":"2026-07-02T00:24:26.284842Z","end":"2026-07-02T00:24:26.390296Z","steps":["trace[183411488] 'process raft request' (duration: 105.042068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.068552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066379423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:24:29.068676Z","caller":"traceutil/trace.go:171","msg":"trace[1284922103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"1.066563148s","start":"2026-07-02T00:24:28.002091Z","end":"2026-07-02T00:24:29.068655Z","steps":["trace[1284922103] 'range keys from in-memory index tree' (duration: 1.06627483s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.068739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:28.002073Z","time spent":"1.066656079s","remote":"127.0.0.1:55174","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-07-02T00:24:29.069215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066914657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:24:29.069264Z","caller":"traceutil/trace.go:171","msg":"trace[777276217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"1.066999579s","start":"2026-07-02T00:24:28.002251Z","end":"2026-07-02T00:24:29.06925Z","steps":["trace[777276217] 'range keys from in-memory index tree' (duration: 1.066756283s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.069295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:28.002162Z","time spent":"1.067124882s","remote":"127.0.0.1:55174","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-07-02T00:24:29.069384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.159009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:29.069416Z","caller":"traceutil/trace.go:171","msg":"trace[367058962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"891.24628ms","start":"2026-07-02T00:24:28.178162Z","end":"2026-07-02T00:24:29.069408Z","steps":["trace[367058962] 'range keys from in-memory index tree' (duration: 891.070945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.069443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:28.178131Z","time spent":"891.305912ms","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:24:29.070218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092836765s","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-07-02T00:24:29.070331Z","caller":"traceutil/trace.go:171","msg":"trace[1880281648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"1.093002599s","start":"2026-07-02T00:24:27.977303Z","end":"2026-07-02T00:24:29.070305Z","steps":["trace[1880281648] 'range keys from in-memory index tree' (duration: 1.090822743s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.070305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.470261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-02T00:24:29.070385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:27.977285Z","time spent":"1.093089981s","remote":"127.0.0.1:55174","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-07-02T00:24:29.070413Z","caller":"traceutil/trace.go:171","msg":"trace[328322987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"600.641885ms","start":"2026-07-02T00:24:28.469747Z","end":"2026-07-02T00:24:29.070389Z","steps":["trace[328322987] 'range keys from in-memory index tree' (duration: 600.281926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:29.07047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:28.469732Z","time spent":"600.724808ms","remote":"127.0.0.1:55174","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-07-02T00:24:45.385995Z","caller":"traceutil/trace.go:171","msg":"trace[950544511] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"225.13369ms","start":"2026-07-02T00:24:45.160843Z","end":"2026-07-02T00:24:45.385976Z","steps":["trace[950544511] 'read index received' (duration: 225.008517ms)","trace[950544511] 'applied index is now lower than readState.Index' (duration: 124.113µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:45.38604Z","caller":"traceutil/trace.go:171","msg":"trace[156045824] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"256.550642ms","start":"2026-07-02T00:24:45.129463Z","end":"2026-07-02T00:24:45.386014Z","steps":["trace[156045824] 'process raft request' (duration: 256.390888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:45.386171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.311896ms","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-07-02T00:24:45.386198Z","caller":"traceutil/trace.go:171","msg":"trace[1371733720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"225.388628ms","start":"2026-07-02T00:24:45.160804Z","end":"2026-07-02T00:24:45.386192Z","steps":["trace[1371733720] 'agreement among raft nodes before linearized reading' (duration: 225.262643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:45.886848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919581963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:24:46.387826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919581963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:24:46.420548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034308554s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:24:46.42123Z","caller":"traceutil/trace.go:171","msg":"trace[300741357] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"1.212775033s","start":"2026-07-02T00:24:45.20842Z","end":"2026-07-02T00:24:46.421195Z","steps":["trace[300741357] 'process raft request' (duration: 1.212519497s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:46.421422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.208397Z","time spent":"1.212927727s","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:46.605125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.023695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T00:24:46.605766Z","caller":"traceutil/trace.go:171","msg":"trace[1790386018] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1327; }","duration":"1.219696035s","start":"2026-07-02T00:24:45.386046Z","end":"2026-07-02T00:24:46.605742Z","steps":["trace[1790386018] 'read index received' (duration: 1.035002102s)","trace[1790386018] 'applied index is now lower than readState.Index' (duration: 184.692263ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:46.605894Z","caller":"traceutil/trace.go:171","msg":"trace[1551545838] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.215301619s","start":"2026-07-02T00:24:45.390572Z","end":"2026-07-02T00:24:46.605874Z","steps":["trace[1551545838] 'process raft request' (duration: 1.214659163s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:46.606032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.390548Z","time spent":"1.215382812s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:46.651983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474176873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:24:46.652015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.462321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:24:46.652069Z","caller":"traceutil/trace.go:171","msg":"trace[661763179] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1280; }","duration":"780.569414ms","start":"2026-07-02T00:24:45.871487Z","end":"2026-07-02T00:24:46.652056Z","steps":["trace[661763179] 'agreement among raft nodes before linearized reading' (duration: 734.631341ms)","trace[661763179] 'count revisions from in-memory index tree' (duration: 45.86091ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:46.652065Z","caller":"traceutil/trace.go:171","msg":"trace[839247835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"1.474298737s","start":"2026-07-02T00:24:45.177745Z","end":"2026-07-02T00:24:46.652044Z","steps":["trace[839247835] 'agreement among raft nodes before linearized reading' (duration: 1.428341794s)","trace[839247835] 'range keys from in-memory index tree' (duration: 45.85133ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:46.652119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.871453Z","time spent":"780.657686ms","remote":"127.0.0.1:55222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:24:46.652126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.177729Z","time spent":"1.474388538s","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:24:46.652109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48781112s","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-07-02T00:24:46.65217Z","caller":"traceutil/trace.go:171","msg":"trace[205878308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"1.487939003s","start":"2026-07-02T00:24:45.164215Z","end":"2026-07-02T00:24:46.652154Z","steps":["trace[205878308] 'agreement among raft nodes before linearized reading' (duration: 1.441911919s)","trace[205878308] 'range keys from in-memory index tree' (duration: 45.84597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:46.652201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.164192Z","time spent":"1.488002694s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:46.652297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486780403s","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-07-02T00:24:46.65235Z","caller":"traceutil/trace.go:171","msg":"trace[1204061613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"1.486874656s","start":"2026-07-02T00:24:45.165456Z","end":"2026-07-02T00:24:46.652331Z","steps":["trace[1204061613] 'agreement among raft nodes before linearized reading' (duration: 1.440640406s)","trace[1204061613] 'range keys from in-memory index tree' (duration: 46.092746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:46.652366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428454136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:46.652391Z","caller":"traceutil/trace.go:171","msg":"trace[1581276648] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1280; }","duration":"1.428507068s","start":"2026-07-02T00:24:45.223875Z","end":"2026-07-02T00:24:46.652382Z","steps":["trace[1581276648] 'agreement among raft nodes before linearized reading' (duration: 1.382172156s)","trace[1581276648] 'count revisions from in-memory index tree' (duration: 46.26669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:46.652391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.165418Z","time spent":"1.486965158s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:46.6524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487008579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-02T00:24:46.652417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.223863Z","time spent":"1.428544169s","remote":"127.0.0.1:38000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:24:46.652388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.355497ms","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-07-02T00:24:46.652517Z","caller":"traceutil/trace.go:171","msg":"trace[1077103735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"770.544741ms","start":"2026-07-02T00:24:45.881936Z","end":"2026-07-02T00:24:46.65248Z","steps":["trace[1077103735] 'agreement among raft nodes before linearized reading' (duration: 724.201149ms)","trace[1077103735] 'range keys from in-memory index tree' (duration: 46.026155ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:46.652438Z","caller":"traceutil/trace.go:171","msg":"trace[794712441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"1.48706137s","start":"2026-07-02T00:24:45.165365Z","end":"2026-07-02T00:24:46.652426Z","steps":["trace[794712441] 'agreement among raft nodes before linearized reading' (duration: 1.440646036s)","trace[794712441] 'range keys from in-memory index tree' (duration: 46.292262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:46.652576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.88191Z","time spent":"770.654254ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:46.652597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:45.165354Z","time spent":"1.487232755s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T00:24:47.676462Z","caller":"traceutil/trace.go:171","msg":"trace[1999354480] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"284.764812ms","start":"2026-07-02T00:24:47.391678Z","end":"2026-07-02T00:24:47.676443Z","steps":["trace[1999354480] 'process raft request' (duration: 284.602838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:58.521921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919582120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:24:59.018056Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304406132s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:24:59.018591Z","caller":"traceutil/trace.go:171","msg":"trace[975890090] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"997.728896ms","start":"2026-07-02T00:24:58.02083Z","end":"2026-07-02T00:24:59.018559Z","steps":["trace[975890090] 'read index received' (duration: 997.432388ms)","trace[975890090] 'applied index is now lower than readState.Index' (duration: 295.178µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:59.018738Z","caller":"traceutil/trace.go:171","msg":"trace[2095211362] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.3051357s","start":"2026-07-02T00:24:57.713525Z","end":"2026-07-02T00:24:59.018661Z","steps":["trace[2095211362] 'process raft request' (duration: 1.304841642s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.018822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.883557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-02T00:24:59.018868Z","caller":"traceutil/trace.go:171","msg":"trace[1444581945] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"998.950108ms","start":"2026-07-02T00:24:58.019899Z","end":"2026-07-02T00:24:59.018849Z","steps":["trace[1444581945] 'agreement among raft nodes before linearized reading' (duration: 998.775824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.018919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:58.019837Z","time spent":"999.071952ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T00:24:59.018943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.477749ms","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:530"} {"level":"info","ts":"2026-07-02T00:24:59.019017Z","caller":"traceutil/trace.go:171","msg":"trace[762493638] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1363; }","duration":"872.609632ms","start":"2026-07-02T00:24:58.146392Z","end":"2026-07-02T00:24:59.019002Z","steps":["trace[762493638] 'agreement among raft nodes before linearized reading' (duration: 872.373376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.019062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:58.146369Z","time spent":"872.684295ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T00:24:59.019457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.798179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T00:24:59.019356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.895496ms","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-07-02T00:24:59.019501Z","caller":"traceutil/trace.go:171","msg":"trace[527013926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"110.87308ms","start":"2026-07-02T00:24:58.908615Z","end":"2026-07-02T00:24:59.019488Z","steps":["trace[527013926] 'agreement among raft nodes before linearized reading' (duration: 110.777798ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:59.019538Z","caller":"traceutil/trace.go:171","msg":"trace[435267625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1363; }","duration":"110.09014ms","start":"2026-07-02T00:24:58.909433Z","end":"2026-07-02T00:24:59.019524Z","steps":["trace[435267625] 'agreement among raft nodes before linearized reading' (duration: 109.652049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.019387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.896033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:24:59.019634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.737911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-02T00:24:59.019746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.963129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T00:24:59.019721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.103643ms","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-07-02T00:24:59.01985Z","caller":"traceutil/trace.go:171","msg":"trace[2131390487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"174.964788ms","start":"2026-07-02T00:24:58.844802Z","end":"2026-07-02T00:24:59.019767Z","steps":["trace[2131390487] 'agreement among raft nodes before linearized reading' (duration: 174.536467ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:59.020012Z","caller":"traceutil/trace.go:171","msg":"trace[389028605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"112.515304ms","start":"2026-07-02T00:24:58.907458Z","end":"2026-07-02T00:24:59.019974Z","steps":["trace[389028605] 'agreement among raft nodes before linearized reading' (duration: 111.526668ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:59.019614Z","caller":"traceutil/trace.go:171","msg":"trace[228536882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"841.153899ms","start":"2026-07-02T00:24:58.178447Z","end":"2026-07-02T00:24:59.019601Z","steps":["trace[228536882] 'agreement among raft nodes before linearized reading' (duration: 840.898933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.020497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:58.178428Z","time spent":"842.027032ms","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:24:59.0191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:57.713503Z","time spent":"1.305412337s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:59.019892Z","caller":"traceutil/trace.go:171","msg":"trace[1614571478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1363; }","duration":"313.36357ms","start":"2026-07-02T00:24:58.706504Z","end":"2026-07-02T00:24:59.019867Z","steps":["trace[1614571478] 'agreement among raft nodes before linearized reading' (duration: 312.831235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:59.02117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:58.706483Z","time spent":"314.669284ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:27:30.310583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919583803,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T00:27:30.427562Z","caller":"traceutil/trace.go:171","msg":"trace[1276190324] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"616.995647ms","start":"2026-07-02T00:27:29.810536Z","end":"2026-07-02T00:27:30.427532Z","steps":["trace[1276190324] 'read index received' (duration: 616.72617ms)","trace[1276190324] 'applied index is now lower than readState.Index' (duration: 268.177µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:27:30.427587Z","caller":"traceutil/trace.go:171","msg":"trace[87648423] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"617.812369ms","start":"2026-07-02T00:27:29.809741Z","end":"2026-07-02T00:27:30.427553Z","steps":["trace[87648423] 'process raft request' (duration: 617.612544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.427754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.80972Z","time spent":"617.947073ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:27:30.427913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.299565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T00:27:30.427997Z","caller":"traceutil/trace.go:171","msg":"trace[2138420547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2058; }","duration":"617.440209ms","start":"2026-07-02T00:27:29.81054Z","end":"2026-07-02T00:27:30.42798Z","steps":["trace[2138420547] 'agreement among raft nodes before linearized reading' (duration: 617.197042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.427986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.672439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:27:30.428047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.81052Z","time spent":"617.516411ms","remote":"127.0.0.1:55174","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-07-02T00:27:30.428234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.403468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T00:27:30.428291Z","caller":"traceutil/trace.go:171","msg":"trace[879991183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2058; }","duration":"560.47943ms","start":"2026-07-02T00:27:29.867802Z","end":"2026-07-02T00:27:30.428281Z","steps":["trace[879991183] 'agreement among raft nodes before linearized reading' (duration: 560.371797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.428292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.966483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T00:27:30.428381Z","caller":"traceutil/trace.go:171","msg":"trace[1397085219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2058; }","duration":"564.106777ms","start":"2026-07-02T00:27:29.864255Z","end":"2026-07-02T00:27:30.428362Z","steps":["trace[1397085219] 'agreement among raft nodes before linearized reading' (duration: 563.926182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.428398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.587733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T00:27:30.428426Z","caller":"traceutil/trace.go:171","msg":"trace[1801194789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2058; }","duration":"560.648944ms","start":"2026-07-02T00:27:29.86777Z","end":"2026-07-02T00:27:30.428419Z","steps":["trace[1801194789] 'agreement among raft nodes before linearized reading' (duration: 560.577643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.428427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.864242Z","time spent":"564.175148ms","remote":"127.0.0.1:55174","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-07-02T00:27:30.428452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.867751Z","time spent":"560.693666ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:27:30.428327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.867787Z","time spent":"560.530602ms","remote":"127.0.0.1:55174","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-07-02T00:27:30.427935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.423946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T00:27:30.428562Z","caller":"traceutil/trace.go:171","msg":"trace[405048793] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:2058; }","duration":"532.091013ms","start":"2026-07-02T00:27:29.896461Z","end":"2026-07-02T00:27:30.428552Z","steps":["trace[405048793] 'agreement among raft nodes before linearized reading' (duration: 531.353073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.42859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.89645Z","time spent":"532.132504ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-07-02T00:27:30.428063Z","caller":"traceutil/trace.go:171","msg":"trace[1878992177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2058; }","duration":"252.765682ms","start":"2026-07-02T00:27:30.175277Z","end":"2026-07-02T00:27:30.428043Z","steps":["trace[1878992177] 'agreement among raft nodes before linearized reading' (duration: 252.68846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.43098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.380287ms","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:531"} {"level":"info","ts":"2026-07-02T00:27:30.431037Z","caller":"traceutil/trace.go:171","msg":"trace[52733345] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2058; }","duration":"620.513041ms","start":"2026-07-02T00:27:29.810506Z","end":"2026-07-02T00:27:30.431019Z","steps":["trace[52733345] 'agreement among raft nodes before linearized reading' (duration: 617.192372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:30.431084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:29.810495Z","time spent":"620.572442ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T00:27:30.932809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919583806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:27:31.433805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919583806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:27:32.433984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001339023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T00:27:32.434072Z","caller":"traceutil/trace.go:171","msg":"trace[238121813] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001468296s","start":"2026-07-02T00:27:30.432584Z","end":"2026-07-02T00:27:32.434052Z","steps":["trace[238121813] 'agreement among raft nodes before linearized reading' (duration: 2.001358513s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:32.434135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.43257Z","time spent":"2.001554848s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 00:27:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T00:27:35.022764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919583806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:27:35.032706Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.601704433s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-02T00:27:35.033057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.430639Z","time spent":"4.602415562s","remote":"127.0.0.1:45684","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} 2026/07/02 00:27:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T00:27:35.431863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.434279Z","time spent":"4.997576823s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/02 00:27:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T00:27:35.431957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.434076Z","time spent":"4.997874891s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/02 00:27:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T00:27:35.56439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.457201ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.564503Z","caller":"traceutil/trace.go:171","msg":"trace[31179879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2058; }","duration":"274.593714ms","start":"2026-07-02T00:27:35.289892Z","end":"2026-07-02T00:27:35.564486Z","steps":["trace[31179879] 'range keys from in-memory index tree' (duration: 274.41863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:27:35.566729Z","caller":"traceutil/trace.go:171","msg":"trace[440489323] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"5.132719338s","start":"2026-07-02T00:27:30.433986Z","end":"2026-07-02T00:27:35.566706Z","steps":["trace[440489323] 'process raft request' (duration: 5.132510602s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.566862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.433974Z","time spent":"5.132830542s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:35.567087Z","caller":"traceutil/trace.go:171","msg":"trace[1357699478] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"5.131903046s","start":"2026-07-02T00:27:30.435175Z","end":"2026-07-02T00:27:35.567078Z","steps":["trace[1357699478] 'process raft request' (duration: 5.13169555s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.567158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.435167Z","time spent":"5.131957567s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:35.567311Z","caller":"traceutil/trace.go:171","msg":"trace[1929979112] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"5.131591948s","start":"2026-07-02T00:27:30.435711Z","end":"2026-07-02T00:27:35.567303Z","steps":["trace[1929979112] 'process raft request' (duration: 5.131319681s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.567417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.435702Z","time spent":"5.13164796s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:35.567689Z","caller":"traceutil/trace.go:171","msg":"trace[774128768] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"4.051384455s","start":"2026-07-02T00:27:31.516279Z","end":"2026-07-02T00:27:35.567663Z","steps":["trace[774128768] 'process raft request' (duration: 4.050998415s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.567734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:32.436976Z","time spent":"3.130755969s","remote":"127.0.0.1:45740","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T00:27:35.567825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:31.516252Z","time spent":"4.051503969s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:35.570735Z","caller":"traceutil/trace.go:171","msg":"trace[603161884] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2141; }","duration":"5.138106491s","start":"2026-07-02T00:27:30.432613Z","end":"2026-07-02T00:27:35.570719Z","steps":["trace[603161884] 'read index received' (duration: 4.600426649s)","trace[603161884] 'applied index is now lower than readState.Index' (duration: 537.679132ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:27:35.570835Z","caller":"traceutil/trace.go:171","msg":"trace[1198683617] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"542.519322ms","start":"2026-07-02T00:27:35.028305Z","end":"2026-07-02T00:27:35.570825Z","steps":["trace[1198683617] 'process raft request' (duration: 542.301486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.570942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.02829Z","time spent":"542.590093ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:35.57096Z","caller":"traceutil/trace.go:171","msg":"trace[350053471] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2066; }","duration":"532.231178ms","start":"2026-07-02T00:27:35.038697Z","end":"2026-07-02T00:27:35.570928Z","steps":["trace[350053471] 'process raft request' (duration: 532.008721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.57109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.038688Z","time spent":"532.32968ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":40,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:27:35.571593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.111362628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.571637Z","caller":"traceutil/trace.go:171","msg":"trace[505920058] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2067; }","duration":"5.111410799s","start":"2026-07-02T00:27:30.460214Z","end":"2026-07-02T00:27:35.571625Z","steps":["trace[505920058] 'agreement among raft nodes before linearized reading' (duration: 5.111329967s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.571664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.460178Z","time spent":"5.111479431s","remote":"127.0.0.1:37990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:27:35.571793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.057848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-02T00:27:35.571841Z","caller":"traceutil/trace.go:171","msg":"trace[1764173470] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2067; }","duration":"533.130801ms","start":"2026-07-02T00:27:35.038701Z","end":"2026-07-02T00:27:35.571832Z","steps":["trace[1764173470] 'agreement among raft nodes before linearized reading' (duration: 532.999308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.571869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.038689Z","time spent":"533.174602ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T00:27:35.571904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.247284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-02T00:27:35.571935Z","caller":"traceutil/trace.go:171","msg":"trace[289891586] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2067; }","duration":"533.286185ms","start":"2026-07-02T00:27:35.038641Z","end":"2026-07-02T00:27:35.571927Z","steps":["trace[289891586] 'agreement among raft nodes before linearized reading' (duration: 533.168002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.571955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.038638Z","time spent":"533.312216ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T00:27:35.572013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.379978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.572036Z","caller":"traceutil/trace.go:171","msg":"trace[1772588862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2067; }","duration":"533.429409ms","start":"2026-07-02T00:27:35.038599Z","end":"2026-07-02T00:27:35.572029Z","steps":["trace[1772588862] 'agreement among raft nodes before linearized reading' (duration: 533.398829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.919154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:27:35.572059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.038583Z","time spent":"533.46847ms","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:27:35.572071Z","caller":"traceutil/trace.go:171","msg":"trace[1464031121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2067; }","duration":"469.938464ms","start":"2026-07-02T00:27:35.102127Z","end":"2026-07-02T00:27:35.572065Z","steps":["trace[1464031121] 'agreement among raft nodes before linearized reading' (duration: 469.908113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.102092Z","time spent":"470.024237ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:27:35.572189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.033494873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.572213Z","caller":"traceutil/trace.go:171","msg":"trace[373105852] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2067; }","duration":"3.033547117s","start":"2026-07-02T00:27:32.53866Z","end":"2026-07-02T00:27:35.572208Z","steps":["trace[373105852] 'agreement among raft nodes before linearized reading' (duration: 3.033512295s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:32.538648Z","time spent":"3.033584777s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:27:35.572258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.099017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T00:27:35.572286Z","caller":"traceutil/trace.go:171","msg":"trace[1821607726] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2067; }","duration":"531.133598ms","start":"2026-07-02T00:27:35.041142Z","end":"2026-07-02T00:27:35.572276Z","steps":["trace[1821607726] 'agreement among raft nodes before linearized reading' (duration: 531.069876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.041136Z","time spent":"531.163748ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T00:27:35.572343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.421851056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.57236Z","caller":"traceutil/trace.go:171","msg":"trace[896570562] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2067; }","duration":"4.421869547s","start":"2026-07-02T00:27:31.150485Z","end":"2026-07-02T00:27:35.572355Z","steps":["trace[896570562] 'agreement among raft nodes before linearized reading' (duration: 4.421839376s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.634974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-02T00:27:35.572379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:31.150418Z","time spent":"4.421956329s","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:27:35.572401Z","caller":"traceutil/trace.go:171","msg":"trace[2135298340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2067; }","duration":"533.660655ms","start":"2026-07-02T00:27:35.038734Z","end":"2026-07-02T00:27:35.572395Z","steps":["trace[2135298340] 'agreement among raft nodes before linearized reading' (duration: 533.611633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:35.038732Z","time spent":"533.685685ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:27:35.572531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.031658472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:27:35.572564Z","caller":"traceutil/trace.go:171","msg":"trace[1356161847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2067; }","duration":"5.031694243s","start":"2026-07-02T00:27:30.540862Z","end":"2026-07-02T00:27:35.572556Z","steps":["trace[1356161847] 'agreement among raft nodes before linearized reading' (duration: 5.031642111s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:35.572587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:30.54063Z","time spent":"5.031951469s","remote":"127.0.0.1:53024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:27:44.46926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152128919583906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:27:44.509712Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039940789s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:27:44.510184Z","caller":"traceutil/trace.go:171","msg":"trace[1013184561] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"1.040462463s","start":"2026-07-02T00:27:43.469687Z","end":"2026-07-02T00:27:44.510149Z","steps":["trace[1013184561] 'process raft request' (duration: 1.040273788s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.510478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.469672Z","time spent":"1.040690289s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:27:44.511025Z","caller":"traceutil/trace.go:171","msg":"trace[398166737] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2187; }","duration":"910.459305ms","start":"2026-07-02T00:27:43.600546Z","end":"2026-07-02T00:27:44.511005Z","steps":["trace[398166737] 'read index received' (duration: 909.380176ms)","trace[398166737] 'applied index is now lower than readState.Index' (duration: 1.078229ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:27:44.511142Z","caller":"traceutil/trace.go:171","msg":"trace[1319168844] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"916.916337ms","start":"2026-07-02T00:27:43.594215Z","end":"2026-07-02T00:27:44.511132Z","steps":["trace[1319168844] 'process raft request' (duration: 916.707181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.511239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.59419Z","time spent":"916.976748ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:27:44.511384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.899533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T00:27:44.511452Z","caller":"traceutil/trace.go:171","msg":"trace[1542071731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2102; }","duration":"905.998296ms","start":"2026-07-02T00:27:43.605438Z","end":"2026-07-02T00:27:44.511436Z","steps":["trace[1542071731] 'agreement among raft nodes before linearized reading' (duration: 905.823702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.511494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.605424Z","time spent":"906.060787ms","remote":"127.0.0.1:55174","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-07-02T00:27:44.511702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.759113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T00:27:44.511741Z","caller":"traceutil/trace.go:171","msg":"trace[972115139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2102; }","duration":"901.809145ms","start":"2026-07-02T00:27:43.609923Z","end":"2026-07-02T00:27:44.511732Z","steps":["trace[972115139] 'agreement among raft nodes before linearized reading' (duration: 901.713431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.511768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.609903Z","time spent":"901.859796ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:27:44.511929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.273716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T00:27:44.511956Z","caller":"traceutil/trace.go:171","msg":"trace[9767563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2102; }","duration":"905.312437ms","start":"2026-07-02T00:27:43.606635Z","end":"2026-07-02T00:27:44.511947Z","steps":["trace[9767563] 'agreement among raft nodes before linearized reading' (duration: 905.196574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.51198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.606628Z","time spent":"905.346609ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:27:44.511701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.149693ms","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:531"} {"level":"info","ts":"2026-07-02T00:27:44.512069Z","caller":"traceutil/trace.go:171","msg":"trace[1420689815] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2102; }","duration":"911.550564ms","start":"2026-07-02T00:27:43.60051Z","end":"2026-07-02T00:27:44.51206Z","steps":["trace[1420689815] 'agreement among raft nodes before linearized reading' (duration: 911.102892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:27:44.51213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:27:43.600496Z","time spent":"911.623295ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-02T00:28:35.183459Z","caller":"traceutil/trace.go:171","msg":"trace[253883519] linearizableReadLoop","detail":"{readStateIndex:2429; appliedIndex:2428; }","duration":"457.608867ms","start":"2026-07-02T00:28:34.725829Z","end":"2026-07-02T00:28:35.183438Z","steps":["trace[253883519] 'read index received' (duration: 457.439392ms)","trace[253883519] 'applied index is now lower than readState.Index' (duration: 168.745µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:28:35.183661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.810822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T00:28:35.183695Z","caller":"traceutil/trace.go:171","msg":"trace[2026823301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2331; }","duration":"457.899114ms","start":"2026-07-02T00:28:34.725787Z","end":"2026-07-02T00:28:35.183686Z","steps":["trace[2026823301] 'agreement among raft nodes before linearized reading' (duration: 457.76025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.183722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.725767Z","time spent":"457.948545ms","remote":"127.0.0.1:55174","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-07-02T00:28:35.183737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.426524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T00:28:35.183805Z","caller":"traceutil/trace.go:171","msg":"trace[1505922844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2331; }","duration":"414.547668ms","start":"2026-07-02T00:28:34.769242Z","end":"2026-07-02T00:28:35.18379Z","steps":["trace[1505922844] 'agreement among raft nodes before linearized reading' (duration: 414.337282ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.183525Z","caller":"traceutil/trace.go:171","msg":"trace[1332822089] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"499.352259ms","start":"2026-07-02T00:28:34.68414Z","end":"2026-07-02T00:28:35.183492Z","steps":["trace[1332822089] 'process raft request' (duration: 499.176325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.183847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.769217Z","time spent":"414.620369ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:28:35.18394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.364383ms","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:531"} {"level":"warn","ts":"2026-07-02T00:28:35.183993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.813035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T00:28:35.184007Z","caller":"traceutil/trace.go:171","msg":"trace[1727276129] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2331; }","duration":"408.445206ms","start":"2026-07-02T00:28:34.775543Z","end":"2026-07-02T00:28:35.183988Z","steps":["trace[1727276129] 'agreement among raft nodes before linearized reading' (duration: 408.24353ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.18405Z","caller":"traceutil/trace.go:171","msg":"trace[429482558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2331; }","duration":"405.889107ms","start":"2026-07-02T00:28:34.778147Z","end":"2026-07-02T00:28:35.184036Z","steps":["trace[429482558] 'agreement among raft nodes before linearized reading' (duration: 405.707541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.184007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.469456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T00:28:35.184055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.775536Z","time spent":"408.509517ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T00:28:35.184112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.77814Z","time spent":"405.938908ms","remote":"127.0.0.1:55174","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-07-02T00:28:35.184136Z","caller":"traceutil/trace.go:171","msg":"trace[960983782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2331; }","duration":"408.597709ms","start":"2026-07-02T00:28:34.775485Z","end":"2026-07-02T00:28:35.184082Z","steps":["trace[960983782] 'agreement among raft nodes before linearized reading' (duration: 408.409264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.1842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.775465Z","time spent":"408.717753ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:28:35.184372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.684117Z","time spent":"500.198322ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:28:35.184661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.86602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T00:28:35.185299Z","caller":"traceutil/trace.go:171","msg":"trace[1174578568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2331; }","duration":"408.593659ms","start":"2026-07-02T00:28:34.776686Z","end":"2026-07-02T00:28:35.18528Z","steps":["trace[1174578568] 'agreement among raft nodes before linearized reading' (duration: 407.705375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.185349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:34.776673Z","time spent":"408.65908ms","remote":"127.0.0.1:55174","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-07-02T00:28:35.91733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.225736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:28:35.917461Z","caller":"traceutil/trace.go:171","msg":"trace[891752152] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2331; }","duration":"628.364419ms","start":"2026-07-02T00:28:35.289079Z","end":"2026-07-02T00:28:35.917444Z","steps":["trace[891752152] 'range keys from in-memory index tree' (duration: 628.211536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.918448Z","caller":"traceutil/trace.go:171","msg":"trace[1178413406] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"729.135389ms","start":"2026-07-02T00:28:35.189292Z","end":"2026-07-02T00:28:35.918427Z","steps":["trace[1178413406] 'process raft request' (duration: 729.018556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.918561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.189274Z","time spent":"729.231631ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:28:35.919231Z","caller":"traceutil/trace.go:171","msg":"trace[1254499759] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"729.719754ms","start":"2026-07-02T00:28:35.189492Z","end":"2026-07-02T00:28:35.919211Z","steps":["trace[1254499759] 'process raft request' (duration: 729.653352ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.919309Z","caller":"traceutil/trace.go:171","msg":"trace[1658863620] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"729.871529ms","start":"2026-07-02T00:28:35.189401Z","end":"2026-07-02T00:28:35.919272Z","steps":["trace[1658863620] 'process raft request' (duration: 729.658133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.919359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.189463Z","time spent":"729.823567ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:28:35.91952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.189383Z","time spent":"730.036113ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:28:35.919785Z","caller":"traceutil/trace.go:171","msg":"trace[161973739] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"730.282489ms","start":"2026-07-02T00:28:35.189492Z","end":"2026-07-02T00:28:35.919774Z","steps":["trace[161973739] 'process raft request' (duration: 729.682953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.919844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.189463Z","time spent":"730.356631ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:28:35.920073Z","caller":"traceutil/trace.go:171","msg":"trace[1298125508] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"728.642285ms","start":"2026-07-02T00:28:35.191414Z","end":"2026-07-02T00:28:35.920056Z","steps":["trace[1298125508] 'process raft request' (duration: 727.973137ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.920188Z","caller":"traceutil/trace.go:171","msg":"trace[467967086] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"728.45657ms","start":"2026-07-02T00:28:35.191718Z","end":"2026-07-02T00:28:35.920174Z","steps":["trace[467967086] 'process raft request' (duration: 728.287066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.920314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.191711Z","time spent":"728.526982ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:28:35.921098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:35.191405Z","time spent":"728.733377ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:28:35.92243Z","caller":"traceutil/trace.go:171","msg":"trace[1717538934] linearizableReadLoop","detail":"{readStateIndex:2436; appliedIndex:2429; }","duration":"250.669266ms","start":"2026-07-02T00:28:35.671746Z","end":"2026-07-02T00:28:35.922415Z","steps":["trace[1717538934] 'read index received' (duration: 246.552507ms)","trace[1717538934] 'applied index is now lower than readState.Index' (duration: 4.115369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:28:35.922633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.878362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-02T00:28:35.922662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.413433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T00:28:35.922678Z","caller":"traceutil/trace.go:171","msg":"trace[1986011081] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2337; }","duration":"250.954354ms","start":"2026-07-02T00:28:35.671711Z","end":"2026-07-02T00:28:35.922665Z","steps":["trace[1986011081] 'agreement among raft nodes before linearized reading' (duration: 250.811151ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.922714Z","caller":"traceutil/trace.go:171","msg":"trace[1767408433] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2337; }","duration":"246.504445ms","start":"2026-07-02T00:28:35.676194Z","end":"2026-07-02T00:28:35.922698Z","steps":["trace[1767408433] 'agreement among raft nodes before linearized reading' (duration: 246.334171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:35.922812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.927943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-02T00:28:35.922824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.646613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-02T00:28:35.922867Z","caller":"traceutil/trace.go:171","msg":"trace[580795734] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2337; }","duration":"239.016815ms","start":"2026-07-02T00:28:35.683834Z","end":"2026-07-02T00:28:35.922851Z","steps":["trace[580795734] 'agreement among raft nodes before linearized reading' (duration: 238.80113ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:35.922893Z","caller":"traceutil/trace.go:171","msg":"trace[1683710401] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2337; }","duration":"233.724634ms","start":"2026-07-02T00:28:35.689143Z","end":"2026-07-02T00:28:35.922868Z","steps":["trace[1683710401] 'agreement among raft nodes before linearized reading' (duration: 233.461008ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:28:49.593834Z","caller":"traceutil/trace.go:171","msg":"trace[2084901002] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"305.563ms","start":"2026-07-02T00:28:49.288241Z","end":"2026-07-02T00:28:49.593804Z","steps":["trace[2084901002] 'process raft request' (duration: 305.401916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:49.594013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:28:49.288217Z","time spent":"305.714865ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}