{"level":"warn","ts":"2026-07-14T17:38:26.740841Z","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-14T17:38:26.740925Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.84:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.84:2380","--initial-cluster=instance=https://199.19.213.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.84: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-14T17:38:26.740985Z","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-14T17:38:26.740993Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-07-14T17:38:26.741007Z","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-14T17:38:26.741651Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"]} {"level":"info","ts":"2026-07-14T17:38:26.741716Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.84: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-14T17:38:26.745116Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.217358ms"} {"level":"info","ts":"2026-07-14T17:38:26.750805Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f823adf772f78a4c","cluster-id":"b5473e4c769d66"} {"level":"info","ts":"2026-07-14T17:38:26.750864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:38:26.750883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 0"} {"level":"info","ts":"2026-07-14T17:38:26.750894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f823adf772f78a4c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:38:26.750905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 1"} {"level":"info","ts":"2026-07-14T17:38:26.750932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"warn","ts":"2026-07-14T17:38:26.759903Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:38:26.762552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:38:26.763596Z","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-14T17:38:26.765555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f823adf772f78a4c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:38:26.765795Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f823adf772f78a4c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:38:26.765946Z","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-14T17:38:26.768216Z","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-14T17:38:26.768226Z","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-14T17:38:26.768498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"info","ts":"2026-07-14T17:38:26.768647Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","added-peer-id":"f823adf772f78a4c","added-peer-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-07-14T17:38:26.77189Z","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-14T17:38:26.771943Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-07-14T17:38:26.771973Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-07-14T17:38:26.77227Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f823adf772f78a4c","initial-advertise-peer-urls":["https://199.19.213.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:38:26.772314Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:38:27.251305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:38:27.251349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:38:27.251372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgPreVoteResp from f823adf772f78a4c at term 1"} {"level":"info","ts":"2026-07-14T17:38:27.251385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:38:27.25139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgVoteResp from f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-07-14T17:38:27.251398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became leader at term 2"} {"level":"info","ts":"2026-07-14T17:38:27.251405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f823adf772f78a4c elected leader f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-07-14T17:38:27.252037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:27.252625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f823adf772f78a4c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.84:2379]}","request-path":"/0/members/f823adf772f78a4c/attributes","cluster-id":"b5473e4c769d66","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:38:27.252886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:38:27.253048Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:38:27.253098Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:38:27.253212Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:38:27.25467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.84:2379"} {"level":"info","ts":"2026-07-14T17:38:27.25541Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:27.255465Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:27.25548Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:27.25811Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T17:38:27.533085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.113394ms","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-14T17:38:27.533142Z","caller":"traceutil/trace.go:171","msg":"trace[1585019193] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"171.179705ms","start":"2026-07-14T17:38:27.361949Z","end":"2026-07-14T17:38:27.533129Z","steps":["trace[1585019193] 'range keys from in-memory index tree' (duration: 171.079022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.533224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.773307ms","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-14T17:38:27.533288Z","caller":"traceutil/trace.go:171","msg":"trace[494792983] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"158.850509ms","start":"2026-07-14T17:38:27.374423Z","end":"2026-07-14T17:38:27.533273Z","steps":["trace[494792983] 'count revisions from in-memory index tree' (duration: 158.734136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.533547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.052965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.533592Z","caller":"traceutil/trace.go:171","msg":"trace[1136705618] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"159.139437ms","start":"2026-07-14T17:38:27.374443Z","end":"2026-07-14T17:38:27.533582Z","steps":["trace[1136705618] 'range keys from in-memory index tree' (duration: 158.985463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.53371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.425978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.533737Z","caller":"traceutil/trace.go:171","msg":"trace[494651123] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"147.476289ms","start":"2026-07-14T17:38:27.386253Z","end":"2026-07-14T17:38:27.533729Z","steps":["trace[494651123] 'count revisions from in-memory index tree' (duration: 147.376016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.533869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.476359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.533901Z","caller":"traceutil/trace.go:171","msg":"trace[696711826] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"147.558092ms","start":"2026-07-14T17:38:27.386336Z","end":"2026-07-14T17:38:27.533894Z","steps":["trace[696711826] 'range keys from in-memory index tree' (duration: 147.410808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.53406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.058986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.534088Z","caller":"traceutil/trace.go:171","msg":"trace[2087404943] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"133.117348ms","start":"2026-07-14T17:38:27.400963Z","end":"2026-07-14T17:38:27.53408Z","steps":["trace[2087404943] 'range keys from in-memory index tree' (duration: 133.017245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.534205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.029715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.534237Z","caller":"traceutil/trace.go:171","msg":"trace[1625970212] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"133.085137ms","start":"2026-07-14T17:38:27.401144Z","end":"2026-07-14T17:38:27.53423Z","steps":["trace[1625970212] 'count revisions from in-memory index tree' (duration: 132.987334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.534335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.562627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.534371Z","caller":"traceutil/trace.go:171","msg":"trace[599087440] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"116.610328ms","start":"2026-07-14T17:38:27.417748Z","end":"2026-07-14T17:38:27.534358Z","steps":["trace[599087440] 'count revisions from in-memory index tree' (duration: 116.526985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.534494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.808654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:38:27.534524Z","caller":"traceutil/trace.go:171","msg":"trace[1179922566] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"116.885366ms","start":"2026-07-14T17:38:27.417632Z","end":"2026-07-14T17:38:27.534517Z","steps":["trace[1179922566] 'range keys from in-memory index tree' (duration: 116.66514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.532987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.931828ms","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-14T17:38:27.534919Z","caller":"traceutil/trace.go:171","msg":"trace[1609664259] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"172.950563ms","start":"2026-07-14T17:38:27.361959Z","end":"2026-07-14T17:38:27.53491Z","steps":["trace[1609664259] 'count revisions from in-memory index tree' (duration: 170.852547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:27.533063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.755488ms","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-14T17:38:27.535073Z","caller":"traceutil/trace.go:171","msg":"trace[1402798425] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"185.822584ms","start":"2026-07-14T17:38:27.349243Z","end":"2026-07-14T17:38:27.535065Z","steps":["trace[1402798425] 'count revisions from in-memory index tree' (duration: 183.689066ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:31.543906Z","caller":"traceutil/trace.go:171","msg":"trace[1573105462] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"144.280401ms","start":"2026-07-14T17:38:31.399599Z","end":"2026-07-14T17:38:31.543879Z","steps":["trace[1573105462] 'process raft request' (duration: 94.837684ms)","trace[1573105462] 'compare' (duration: 49.255072ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:31.544151Z","caller":"traceutil/trace.go:171","msg":"trace[690083097] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"118.041857ms","start":"2026-07-14T17:38:31.426081Z","end":"2026-07-14T17:38:31.544123Z","steps":["trace[690083097] 'read index received' (duration: 68.286601ms)","trace[690083097] 'applied index is now lower than readState.Index' (duration: 49.753656ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:31.544269Z","caller":"traceutil/trace.go:171","msg":"trace[822733249] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"144.109397ms","start":"2026-07-14T17:38:31.400147Z","end":"2026-07-14T17:38:31.544257Z","steps":["trace[822733249] 'process raft request' (duration: 143.680816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:31.544366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.260853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:38:31.544724Z","caller":"traceutil/trace.go:171","msg":"trace[1470315504] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:254; }","duration":"118.657664ms","start":"2026-07-14T17:38:31.426047Z","end":"2026-07-14T17:38:31.544705Z","steps":["trace[1470315504] 'agreement among raft nodes before linearized reading' (duration: 118.200341ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:31.698143Z","caller":"traceutil/trace.go:171","msg":"trace[195706385] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"111.661113ms","start":"2026-07-14T17:38:31.586444Z","end":"2026-07-14T17:38:31.698105Z","steps":["trace[195706385] 'process raft request' (duration: 111.206761ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:31.712633Z","caller":"traceutil/trace.go:171","msg":"trace[290616991] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"109.820273ms","start":"2026-07-14T17:38:31.602726Z","end":"2026-07-14T17:38:31.712546Z","steps":["trace[290616991] 'process raft request' (duration: 109.632258ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:36.879981Z","caller":"traceutil/trace.go:171","msg":"trace[845659486] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"132.369477ms","start":"2026-07-14T17:38:36.747585Z","end":"2026-07-14T17:38:36.879955Z","steps":["trace[845659486] 'read index received' (duration: 9.827327ms)","trace[845659486] 'applied index is now lower than readState.Index' (duration: 122.54109ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:36.880374Z","caller":"traceutil/trace.go:171","msg":"trace[919037863] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"132.892251ms","start":"2026-07-14T17:38:36.747448Z","end":"2026-07-14T17:38:36.88034Z","steps":["trace[919037863] 'process raft request' (duration: 132.409558ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:36.880385Z","caller":"traceutil/trace.go:171","msg":"trace[1320532750] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"132.436149ms","start":"2026-07-14T17:38:36.747902Z","end":"2026-07-14T17:38:36.880338Z","steps":["trace[1320532750] 'process raft request' (duration: 132.010598ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:36.880483Z","caller":"traceutil/trace.go:171","msg":"trace[71937926] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"133.053146ms","start":"2026-07-14T17:38:36.747391Z","end":"2026-07-14T17:38:36.880444Z","steps":["trace[71937926] 'process raft request' (duration: 93.1796ms)","trace[71937926] 'compare' (duration: 39.181897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:36.880979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.527971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-14T17:38:36.88107Z","caller":"traceutil/trace.go:171","msg":"trace[100340770] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"133.504408ms","start":"2026-07-14T17:38:36.747546Z","end":"2026-07-14T17:38:36.881051Z","steps":["trace[100340770] 'agreement among raft nodes before linearized reading' (duration: 132.513971ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:37.115816Z","caller":"traceutil/trace.go:171","msg":"trace[2080719324] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"211.379821ms","start":"2026-07-14T17:38:36.904398Z","end":"2026-07-14T17:38:37.115778Z","steps":["trace[2080719324] 'read index received' (duration: 124.76635ms)","trace[2080719324] 'applied index is now lower than readState.Index' (duration: 86.612411ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:37.11593Z","caller":"traceutil/trace.go:171","msg":"trace[1404777442] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"226.183844ms","start":"2026-07-14T17:38:36.889715Z","end":"2026-07-14T17:38:37.115899Z","steps":["trace[1404777442] 'process raft request' (duration: 139.43069ms)","trace[1404777442] 'compare' (duration: 86.470567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:37.116122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.892497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-14T17:38:37.116164Z","caller":"traceutil/trace.go:171","msg":"trace[1399746204] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"228.97855ms","start":"2026-07-14T17:38:36.887173Z","end":"2026-07-14T17:38:37.116152Z","steps":["trace[1399746204] 'agreement among raft nodes before linearized reading' (duration: 228.750954ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:37.181749Z","caller":"traceutil/trace.go:171","msg":"trace[1711483398] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"264.55915ms","start":"2026-07-14T17:38:36.917169Z","end":"2026-07-14T17:38:37.181728Z","steps":["trace[1711483398] 'process raft request' (duration: 264.299633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:37.181967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.970729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-14T17:38:37.182016Z","caller":"traceutil/trace.go:171","msg":"trace[506872651] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:378; }","duration":"171.35218ms","start":"2026-07-14T17:38:37.010653Z","end":"2026-07-14T17:38:37.182005Z","steps":["trace[506872651] 'agreement among raft nodes before linearized reading' (duration: 170.97133ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:37.498696Z","caller":"traceutil/trace.go:171","msg":"trace[188475578] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"315.287421ms","start":"2026-07-14T17:38:37.183385Z","end":"2026-07-14T17:38:37.498672Z","steps":["trace[188475578] 'read index received' (duration: 314.287024ms)","trace[188475578] 'applied index is now lower than readState.Index' (duration: 999.437µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:37.498848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.437145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-qfkqv\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-07-14T17:38:37.498889Z","caller":"traceutil/trace.go:171","msg":"trace[1502799029] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-qfkqv; range_end:; response_count:1; response_revision:379; }","duration":"315.534579ms","start":"2026-07-14T17:38:37.183343Z","end":"2026-07-14T17:38:37.498878Z","steps":["trace[1502799029] 'agreement among raft nodes before linearized reading' (duration: 315.418466ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:37.498867Z","caller":"traceutil/trace.go:171","msg":"trace[195858466] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"374.30242ms","start":"2026-07-14T17:38:37.124523Z","end":"2026-07-14T17:38:37.498825Z","steps":["trace[195858466] 'process raft request' (duration: 373.228461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:37.498927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.183327Z","time spent":"315.5921ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4484,"request content":"key:\"/registry/pods/kube-system/kube-proxy-qfkqv\" "} {"level":"warn","ts":"2026-07-14T17:38:37.499523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.124495Z","time spent":"374.448385ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:38:38.448817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.338189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:38:38.448928Z","caller":"traceutil/trace.go:171","msg":"trace[1253623525] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"873.258709ms","start":"2026-07-14T17:38:37.575649Z","end":"2026-07-14T17:38:38.448908Z","steps":["trace[1253623525] 'process raft request' (duration: 406.609021ms)","trace[1253623525] 'compare' (duration: 466.133174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:38.449415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.575623Z","time spent":"873.760193ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:38:39.135699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.151492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:38:39.135951Z","caller":"traceutil/trace.go:171","msg":"trace[1715198488] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"1.559871782s","start":"2026-07-14T17:38:37.576055Z","end":"2026-07-14T17:38:39.135927Z","steps":["trace[1715198488] 'process raft request' (duration: 1.132429153s)","trace[1715198488] 'compare' (duration: 426.765571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:39.136064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.576035Z","time spent":"1.559996725s","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:38:39.136693Z","caller":"traceutil/trace.go:171","msg":"trace[2112623336] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"1.560390876s","start":"2026-07-14T17:38:37.576284Z","end":"2026-07-14T17:38:39.136675Z","steps":["trace[2112623336] 'process raft request' (duration: 1.559594425s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.136914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.576274Z","time spent":"1.560463008s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:38:39.136924Z","caller":"traceutil/trace.go:171","msg":"trace[581290151] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.560264653s","start":"2026-07-14T17:38:37.576643Z","end":"2026-07-14T17:38:39.136908Z","steps":["trace[581290151] 'process raft request' (duration: 1.559910953s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.576618Z","time spent":"1.560342595s","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:38:39.137068Z","caller":"traceutil/trace.go:171","msg":"trace[421307169] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:394; }","duration":"1.403788058s","start":"2026-07-14T17:38:37.733273Z","end":"2026-07-14T17:38:39.137061Z","steps":["trace[421307169] 'read index received' (duration: 248.981025ms)","trace[421307169] 'applied index is now lower than readState.Index' (duration: 1.154806323s)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:39.137108Z","caller":"traceutil/trace.go:171","msg":"trace[496351862] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"1.557766005s","start":"2026-07-14T17:38:37.579259Z","end":"2026-07-14T17:38:39.137025Z","steps":["trace[496351862] 'process raft request' (duration: 1.55757194s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.499559ms","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-14T17:38:39.137195Z","caller":"traceutil/trace.go:171","msg":"trace[46817975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"668.548791ms","start":"2026-07-14T17:38:38.468641Z","end":"2026-07-14T17:38:39.13719Z","steps":["trace[46817975] 'agreement among raft nodes before linearized reading' (duration: 668.51996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:38.468622Z","time spent":"668.584991ms","remote":"127.0.0.1:40978","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-14T17:38:39.137283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.57925Z","time spent":"1.557930139s","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:38:39.137313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.977935ms","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-14T17:38:39.137357Z","caller":"traceutil/trace.go:171","msg":"trace[1491489612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"253.044457ms","start":"2026-07-14T17:38:38.884303Z","end":"2026-07-14T17:38:39.137347Z","steps":["trace[1491489612] 'agreement among raft nodes before linearized reading' (duration: 252.982245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4038706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-07-14T17:38:39.137404Z","caller":"traceutil/trace.go:171","msg":"trace[1160960631] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:389; }","duration":"1.404172789s","start":"2026-07-14T17:38:37.733219Z","end":"2026-07-14T17:38:39.137392Z","steps":["trace[1160960631] 'agreement among raft nodes before linearized reading' (duration: 1.403885681s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:37.733199Z","time spent":"1.40422368s","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-14T17:38:39.137399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.235001ms","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-14T17:38:39.137476Z","caller":"traceutil/trace.go:171","msg":"trace[657691961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"479.319504ms","start":"2026-07-14T17:38:38.658138Z","end":"2026-07-14T17:38:39.137457Z","steps":["trace[657691961] 'agreement among raft nodes before linearized reading' (duration: 479.145519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:38.658126Z","time spent":"479.388055ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:38:39.137551Z","caller":"traceutil/trace.go:171","msg":"trace[1055797056] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"502.125074ms","start":"2026-07-14T17:38:38.634804Z","end":"2026-07-14T17:38:39.136929Z","steps":["trace[1055797056] 'process raft request' (duration: 502.091623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:39.137751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:38.634776Z","time spent":"502.865315ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:38:39.699754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965515317468283499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:38:40.1278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.619541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:38:40.128023Z","caller":"traceutil/trace.go:171","msg":"trace[1137225194] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:401; }","duration":"928.615859ms","start":"2026-07-14T17:38:39.199391Z","end":"2026-07-14T17:38:40.128007Z","steps":["trace[1137225194] 'read index received' (duration: 612.598886ms)","trace[1137225194] 'applied index is now lower than readState.Index' (duration: 316.016193ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:40.12812Z","caller":"traceutil/trace.go:171","msg":"trace[1820086726] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"984.754408ms","start":"2026-07-14T17:38:39.143353Z","end":"2026-07-14T17:38:40.128107Z","steps":["trace[1820086726] 'process raft request' (duration: 668.740296ms)","trace[1820086726] 'compare' (duration: 315.448767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:38:40.128206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:39.143334Z","time spent":"984.8191ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:38:40.12824Z","caller":"traceutil/trace.go:171","msg":"trace[2145031782] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"982.856116ms","start":"2026-07-14T17:38:39.145353Z","end":"2026-07-14T17:38:40.12821Z","steps":["trace[2145031782] 'process raft request' (duration: 982.607489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:40.128378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:39.145335Z","time spent":"982.97198ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:38:40.128437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.05241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-14T17:38:40.128477Z","caller":"traceutil/trace.go:171","msg":"trace[651497900] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:393; }","duration":"929.126672ms","start":"2026-07-14T17:38:39.19934Z","end":"2026-07-14T17:38:40.128466Z","steps":["trace[651497900] 'agreement among raft nodes before linearized reading' (duration: 929.05913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:40.128511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:39.19932Z","time spent":"929.185034ms","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3796,"request content":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" "} {"level":"info","ts":"2026-07-14T17:38:40.128552Z","caller":"traceutil/trace.go:171","msg":"trace[1976437773] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"984.304115ms","start":"2026-07-14T17:38:39.144238Z","end":"2026-07-14T17:38:40.128542Z","steps":["trace[1976437773] 'process raft request' (duration: 983.672968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:38:40.128612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:38:39.14423Z","time spent":"984.352287ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:39:07.374038Z","caller":"traceutil/trace.go:171","msg":"trace[732790664] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"314.395218ms","start":"2026-07-14T17:39:07.059614Z","end":"2026-07-14T17:39:07.37401Z","steps":["trace[732790664] 'process raft request' (duration: 314.280345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:07.374209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.059592Z","time spent":"314.544702ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:07.380799Z","caller":"traceutil/trace.go:171","msg":"trace[1504926905] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"317.367969ms","start":"2026-07-14T17:39:07.063379Z","end":"2026-07-14T17:39:07.380747Z","steps":["trace[1504926905] 'process raft request' (duration: 317.182014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:07.38091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.06336Z","time spent":"317.495423ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:07.380968Z","caller":"traceutil/trace.go:171","msg":"trace[776096600] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"311.238282ms","start":"2026-07-14T17:39:07.0697Z","end":"2026-07-14T17:39:07.380939Z","steps":["trace[776096600] 'process raft request' (duration: 311.074348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:07.381051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.069697Z","time spent":"311.316674ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:07.38107Z","caller":"traceutil/trace.go:171","msg":"trace[1611961583] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"311.210771ms","start":"2026-07-14T17:39:07.06983Z","end":"2026-07-14T17:39:07.381041Z","steps":["trace[1611961583] 'process raft request' (duration: 310.988025ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381187Z","caller":"traceutil/trace.go:171","msg":"trace[166509809] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"307.444719ms","start":"2026-07-14T17:39:07.073708Z","end":"2026-07-14T17:39:07.381152Z","steps":["trace[166509809] 'process raft request' (duration: 307.326336ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381241Z","caller":"traceutil/trace.go:171","msg":"trace[1029931521] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"295.283927ms","start":"2026-07-14T17:39:07.085939Z","end":"2026-07-14T17:39:07.381223Z","steps":["trace[1029931521] 'process raft request' (duration: 295.153804ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381077Z","caller":"traceutil/trace.go:171","msg":"trace[1321300375] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"308.336664ms","start":"2026-07-14T17:39:07.072718Z","end":"2026-07-14T17:39:07.381055Z","steps":["trace[1321300375] 'process raft request' (duration: 308.236561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:07.381328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.072708Z","time spent":"308.59931ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:39:07.381323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.0737Z","time spent":"307.559452ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:39:07.381301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.069825Z","time spent":"311.358346ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:07.381136Z","caller":"traceutil/trace.go:171","msg":"trace[807911895] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"308.323082ms","start":"2026-07-14T17:39:07.072801Z","end":"2026-07-14T17:39:07.381124Z","steps":["trace[807911895] 'process raft request' (duration: 308.195529ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.380954Z","caller":"traceutil/trace.go:171","msg":"trace[1754063815] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"311.180721ms","start":"2026-07-14T17:39:07.069657Z","end":"2026-07-14T17:39:07.380837Z","steps":["trace[1754063815] 'process raft request' (duration: 311.002666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:07.381651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.072795Z","time spent":"308.737605ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:39:07.381796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.091015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T17:39:07.381454Z","caller":"traceutil/trace.go:171","msg":"trace[508881264] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"289.777017ms","start":"2026-07-14T17:39:07.091664Z","end":"2026-07-14T17:39:07.381441Z","steps":["trace[508881264] 'process raft request' (duration: 289.5299ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381848Z","caller":"traceutil/trace.go:171","msg":"trace[257235512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"130.174668ms","start":"2026-07-14T17:39:07.251652Z","end":"2026-07-14T17:39:07.381827Z","steps":["trace[257235512] 'agreement among raft nodes before linearized reading' (duration: 130.057494ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381643Z","caller":"traceutil/trace.go:171","msg":"trace[754900960] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"281.250155ms","start":"2026-07-14T17:39:07.100381Z","end":"2026-07-14T17:39:07.381631Z","steps":["trace[754900960] 'process raft request' (duration: 280.875795ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381659Z","caller":"traceutil/trace.go:171","msg":"trace[1749104557] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"143.597224ms","start":"2026-07-14T17:39:07.238046Z","end":"2026-07-14T17:39:07.381644Z","steps":["trace[1749104557] 'process raft request' (duration: 143.485621ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.381673Z","caller":"traceutil/trace.go:171","msg":"trace[1028335869] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:558; }","duration":"129.961072ms","start":"2026-07-14T17:39:07.251689Z","end":"2026-07-14T17:39:07.38165Z","steps":["trace[1028335869] 'read index received' (duration: 123.156916ms)","trace[1028335869] 'applied index is now lower than readState.Index' (duration: 6.803106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:07.382209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:07.069645Z","time spent":"312.094645ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:08.417039Z","caller":"traceutil/trace.go:171","msg":"trace[1501767642] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"101.441685ms","start":"2026-07-14T17:39:08.31558Z","end":"2026-07-14T17:39:08.417021Z","steps":["trace[1501767642] 'process raft request' (duration: 101.386884ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:08.417173Z","caller":"traceutil/trace.go:171","msg":"trace[1469227082] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"117.165753ms","start":"2026-07-14T17:39:08.299998Z","end":"2026-07-14T17:39:08.417163Z","steps":["trace[1469227082] 'read index received' (duration: 23.620174ms)","trace[1469227082] 'applied index is now lower than readState.Index' (duration: 93.544319ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:08.417248Z","caller":"traceutil/trace.go:171","msg":"trace[1018567344] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"117.275827ms","start":"2026-07-14T17:39:08.299948Z","end":"2026-07-14T17:39:08.417224Z","steps":["trace[1018567344] 'process raft request' (duration: 23.659585ms)","trace[1018567344] 'compare' (duration: 93.233941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:08.417379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.364088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"warn","ts":"2026-07-14T17:39:08.417372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.78352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:08.417446Z","caller":"traceutil/trace.go:171","msg":"trace[1709470084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:610; }","duration":"117.462351ms","start":"2026-07-14T17:39:08.299973Z","end":"2026-07-14T17:39:08.417436Z","steps":["trace[1709470084] 'agreement among raft nodes before linearized reading' (duration: 117.271236ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:08.417449Z","caller":"traceutil/trace.go:171","msg":"trace[256227974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"106.897593ms","start":"2026-07-14T17:39:08.310532Z","end":"2026-07-14T17:39:08.417429Z","steps":["trace[256227974] 'agreement among raft nodes before linearized reading' (duration: 106.761049ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:13.395983Z","caller":"traceutil/trace.go:171","msg":"trace[1090272708] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"163.25116ms","start":"2026-07-14T17:39:13.232704Z","end":"2026-07-14T17:39:13.395955Z","steps":["trace[1090272708] 'process raft request' (duration: 163.052314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:13.607261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.671264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:39:13.607485Z","caller":"traceutil/trace.go:171","msg":"trace[176174593] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"178.425243ms","start":"2026-07-14T17:39:13.42903Z","end":"2026-07-14T17:39:13.607455Z","steps":["trace[176174593] 'process raft request' (duration: 178.349141ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:13.607521Z","caller":"traceutil/trace.go:171","msg":"trace[1475484691] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"180.067148ms","start":"2026-07-14T17:39:13.427425Z","end":"2026-07-14T17:39:13.607493Z","steps":["trace[1475484691] 'read index received' (duration: 10.043014ms)","trace[1475484691] 'applied index is now lower than readState.Index' (duration: 170.021924ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:13.607578Z","caller":"traceutil/trace.go:171","msg":"trace[1684999703] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"181.325922ms","start":"2026-07-14T17:39:13.426216Z","end":"2026-07-14T17:39:13.607542Z","steps":["trace[1684999703] 'process raft request' (duration: 11.241487ms)","trace[1684999703] 'compare' (duration: 169.502179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:13.60779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.333235ms","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-14T17:39:13.607839Z","caller":"traceutil/trace.go:171","msg":"trace[1355115987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"180.429198ms","start":"2026-07-14T17:39:13.427395Z","end":"2026-07-14T17:39:13.607824Z","steps":["trace[1355115987] 'agreement among raft nodes before linearized reading' (duration: 180.214042ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:13.9077Z","caller":"traceutil/trace.go:171","msg":"trace[1278589747] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"105.64964ms","start":"2026-07-14T17:39:13.802023Z","end":"2026-07-14T17:39:13.907673Z","steps":["trace[1278589747] 'process raft request' (duration: 100.723255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:14.71808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.299681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:39:14.718225Z","caller":"traceutil/trace.go:171","msg":"trace[1040140897] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"430.935213ms","start":"2026-07-14T17:39:14.28727Z","end":"2026-07-14T17:39:14.718206Z","steps":["trace[1040140897] 'read index received' (duration: 48.581µs)","trace[1040140897] 'applied index is now lower than readState.Index' (duration: 430.885082ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:14.718511Z","caller":"traceutil/trace.go:171","msg":"trace[1493155435] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"754.259436ms","start":"2026-07-14T17:39:13.964146Z","end":"2026-07-14T17:39:14.718405Z","steps":["trace[1493155435] 'process raft request' (duration: 161.552183ms)","trace[1493155435] 'compare' (duration: 592.142077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:14.718591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.297864ms","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-14T17:39:14.718648Z","caller":"traceutil/trace.go:171","msg":"trace[568694448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"431.438557ms","start":"2026-07-14T17:39:14.287193Z","end":"2026-07-14T17:39:14.718632Z","steps":["trace[568694448] 'agreement among raft nodes before linearized reading' (duration: 431.208731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:14.718658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:13.964123Z","time spent":"754.473811ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:39:14.718695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:14.287163Z","time spent":"431.523319ms","remote":"127.0.0.1:40978","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-14T17:39:14.718751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.864635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T17:39:14.71855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.356373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:14.718894Z","caller":"traceutil/trace.go:171","msg":"trace[929061682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"410.719133ms","start":"2026-07-14T17:39:14.308139Z","end":"2026-07-14T17:39:14.718858Z","steps":["trace[929061682] 'agreement among raft nodes before linearized reading' (duration: 410.278411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:14.719123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:14.308128Z","time spent":"410.97947ms","remote":"127.0.0.1:40692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:39:14.718813Z","caller":"traceutil/trace.go:171","msg":"trace[896923160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"280.969087ms","start":"2026-07-14T17:39:14.437829Z","end":"2026-07-14T17:39:14.718798Z","steps":["trace[896923160] 'agreement among raft nodes before linearized reading' (duration: 280.7004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:15.250288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.622103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:39:15.250561Z","caller":"traceutil/trace.go:171","msg":"trace[275014251] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"527.477535ms","start":"2026-07-14T17:39:14.723028Z","end":"2026-07-14T17:39:15.250506Z","steps":["trace[275014251] 'process raft request' (duration: 204.561255ms)","trace[275014251] 'compare' (duration: 322.497079ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:15.250625Z","caller":"traceutil/trace.go:171","msg":"trace[1514017815] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"524.610787ms","start":"2026-07-14T17:39:14.725965Z","end":"2026-07-14T17:39:15.250576Z","steps":["trace[1514017815] 'read index received' (duration: 201.751218ms)","trace[1514017815] 'applied index is now lower than readState.Index' (duration: 322.858599ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:15.250703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:14.72298Z","time spent":"527.6476ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:39:15.250822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.847204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-14T17:39:15.250857Z","caller":"traceutil/trace.go:171","msg":"trace[2043787630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:651; }","duration":"524.897806ms","start":"2026-07-14T17:39:14.725948Z","end":"2026-07-14T17:39:15.250846Z","steps":["trace[2043787630] 'agreement among raft nodes before linearized reading' (duration: 524.712441ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:15.250923Z","caller":"traceutil/trace.go:171","msg":"trace[1929780698] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"525.277026ms","start":"2026-07-14T17:39:14.725396Z","end":"2026-07-14T17:39:15.250673Z","steps":["trace[1929780698] 'process raft request' (duration: 524.991608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:15.250982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:14.725941Z","time spent":"525.029069ms","remote":"127.0.0.1:40896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-14T17:39:15.251656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:14.725383Z","time spent":"525.753218ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:39:16.505534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.684994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:39:16.505926Z","caller":"traceutil/trace.go:171","msg":"trace[927199510] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.244051094s","start":"2026-07-14T17:39:15.261852Z","end":"2026-07-14T17:39:16.505903Z","steps":["trace[927199510] 'process raft request' (duration: 727.938288ms)","trace[927199510] 'compare' (duration: 515.451698ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:16.506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:15.261826Z","time spent":"1.244140587s","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:39:16.537306Z","caller":"traceutil/trace.go:171","msg":"trace[1447581295] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"924.069484ms","start":"2026-07-14T17:39:15.613209Z","end":"2026-07-14T17:39:16.537279Z","steps":["trace[1447581295] 'read index received' (duration: 376.596014ms)","trace[1447581295] 'applied index is now lower than readState.Index' (duration: 547.47209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:16.538531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.303097ms","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-14T17:39:16.538646Z","caller":"traceutil/trace.go:171","msg":"trace[1543110222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"925.467292ms","start":"2026-07-14T17:39:15.613159Z","end":"2026-07-14T17:39:16.538626Z","steps":["trace[1543110222] 'agreement among raft nodes before linearized reading' (duration: 924.233758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:16.538712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:15.613137Z","time spent":"925.561505ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:39:16.547665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.314747ms","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-14T17:39:16.547718Z","caller":"traceutil/trace.go:171","msg":"trace[813342543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"896.40997ms","start":"2026-07-14T17:39:15.651296Z","end":"2026-07-14T17:39:16.547706Z","steps":["trace[813342543] 'agreement among raft nodes before linearized reading' (duration: 896.147903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:16.547751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:15.651275Z","time spent":"896.468541ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:39:16.548083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.625348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:16.548192Z","caller":"traceutil/trace.go:171","msg":"trace[241498423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"240.779972ms","start":"2026-07-14T17:39:16.307393Z","end":"2026-07-14T17:39:16.548173Z","steps":["trace[241498423] 'agreement among raft nodes before linearized reading' (duration: 240.578726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:31.888332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.273097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qhc66\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-14T17:39:31.88843Z","caller":"traceutil/trace.go:171","msg":"trace[1045514215] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qhc66; range_end:; response_count:1; response_revision:807; }","duration":"382.420261ms","start":"2026-07-14T17:39:31.50599Z","end":"2026-07-14T17:39:31.88841Z","steps":["trace[1045514215] 'range keys from in-memory index tree' (duration: 382.02912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:31.888476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:31.505966Z","time spent":"382.500094ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qhc66\" "} {"level":"info","ts":"2026-07-14T17:39:32.011973Z","caller":"traceutil/trace.go:171","msg":"trace[101231337] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"117.054383ms","start":"2026-07-14T17:39:31.894892Z","end":"2026-07-14T17:39:32.011947Z","steps":["trace[101231337] 'process raft request' (duration: 116.878709ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:37.602682Z","caller":"traceutil/trace.go:171","msg":"trace[1622349813] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"124.172712ms","start":"2026-07-14T17:39:37.478488Z","end":"2026-07-14T17:39:37.60266Z","steps":["trace[1622349813] 'process raft request' (duration: 107.922259ms)","trace[1622349813] 'compare' (duration: 16.14949ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:55.61065Z","caller":"traceutil/trace.go:171","msg":"trace[1850648407] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"137.923285ms","start":"2026-07-14T17:39:55.472677Z","end":"2026-07-14T17:39:55.6106Z","steps":["trace[1850648407] 'process raft request' (duration: 137.639387ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:07.838208Z","caller":"traceutil/trace.go:171","msg":"trace[850496933] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"148.178121ms","start":"2026-07-14T17:40:07.689999Z","end":"2026-07-14T17:40:07.838177Z","steps":["trace[850496933] 'read index received' (duration: 147.986306ms)","trace[850496933] 'applied index is now lower than readState.Index' (duration: 190.445µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:07.838285Z","caller":"traceutil/trace.go:171","msg":"trace[625718276] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"321.522644ms","start":"2026-07-14T17:40:07.516733Z","end":"2026-07-14T17:40:07.838256Z","steps":["trace[625718276] 'process raft request' (duration: 321.260297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:07.838421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:07.516714Z","time spent":"321.624796ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:07.838481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.447889ms","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-14T17:40:07.83852Z","caller":"traceutil/trace.go:171","msg":"trace[880388886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"148.560072ms","start":"2026-07-14T17:40:07.689949Z","end":"2026-07-14T17:40:07.838509Z","steps":["trace[880388886] 'agreement among raft nodes before linearized reading' (duration: 148.394157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.443797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965515317468284850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:40:08.568373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.295819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:08.568458Z","caller":"traceutil/trace.go:171","msg":"trace[152645167] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"726.583156ms","start":"2026-07-14T17:40:07.841859Z","end":"2026-07-14T17:40:08.568442Z","steps":["trace[152645167] 'process raft request' (duration: 76.137052ms)","trace[152645167] 'compare' (duration: 650.069783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:08.568501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:07.841835Z","time spent":"726.646458ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:08.589847Z","caller":"traceutil/trace.go:171","msg":"trace[2121636134] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"646.36871ms","start":"2026-07-14T17:40:07.943453Z","end":"2026-07-14T17:40:08.589822Z","steps":["trace[2121636134] 'read index received' (duration: 646.266467ms)","trace[2121636134] 'applied index is now lower than readState.Index' (duration: 101.293µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.58994Z","caller":"traceutil/trace.go:171","msg":"trace[851578659] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"747.455579ms","start":"2026-07-14T17:40:07.842457Z","end":"2026-07-14T17:40:08.589913Z","steps":["trace[851578659] 'process raft request' (duration: 747.238052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.590003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.525395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:08.590041Z","caller":"traceutil/trace.go:171","msg":"trace[608974272] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1029; }","duration":"646.636608ms","start":"2026-07-14T17:40:07.943392Z","end":"2026-07-14T17:40:08.590029Z","steps":["trace[608974272] 'agreement among raft nodes before linearized reading' (duration: 646.559706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.59006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:07.842434Z","time spent":"747.562761ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:08.590082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:07.943376Z","time spent":"646.69578ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:40:08.590073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.652172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:08.590131Z","caller":"traceutil/trace.go:171","msg":"trace[382233939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"281.748505ms","start":"2026-07-14T17:40:08.308366Z","end":"2026-07-14T17:40:08.590114Z","steps":["trace[382233939] 'agreement among raft nodes before linearized reading' (duration: 281.656493ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:24.050836Z","caller":"traceutil/trace.go:171","msg":"trace[1615286509] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"151.399191ms","start":"2026-07-14T17:40:23.899414Z","end":"2026-07-14T17:40:24.050813Z","steps":["trace[1615286509] 'process raft request' (duration: 151.250657ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:30.938279Z","caller":"traceutil/trace.go:171","msg":"trace[880750189] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"226.014702ms","start":"2026-07-14T17:40:30.712241Z","end":"2026-07-14T17:40:30.938255Z","steps":["trace[880750189] 'process raft request' (duration: 225.887598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.223879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.521318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:31.224005Z","caller":"traceutil/trace.go:171","msg":"trace[1463991875] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1147; }","duration":"415.503865ms","start":"2026-07-14T17:40:30.808483Z","end":"2026-07-14T17:40:31.223987Z","steps":["trace[1463991875] 'read index received' (duration: 129.564808ms)","trace[1463991875] 'applied index is now lower than readState.Index' (duration: 285.937747ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:31.22406Z","caller":"traceutil/trace.go:171","msg":"trace[972381224] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"511.420284ms","start":"2026-07-14T17:40:30.712599Z","end":"2026-07-14T17:40:31.224019Z","steps":["trace[972381224] 'process raft request' (duration: 378.68786ms)","trace[972381224] 'compare' (duration: 132.407665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:31.224189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.872994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:40:31.224209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.727291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T17:40:31.224253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.757293ms","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-14T17:40:31.224252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:30.712588Z","time spent":"511.555177ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:31.22427Z","caller":"traceutil/trace.go:171","msg":"trace[1317871080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"415.829113ms","start":"2026-07-14T17:40:30.808427Z","end":"2026-07-14T17:40:31.224256Z","steps":["trace[1317871080] 'agreement among raft nodes before linearized reading' (duration: 415.642138ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:31.224305Z","caller":"traceutil/trace.go:171","msg":"trace[1536619948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"166.840095ms","start":"2026-07-14T17:40:31.05745Z","end":"2026-07-14T17:40:31.22429Z","steps":["trace[1536619948] 'agreement among raft nodes before linearized reading' (duration: 166.700882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.224323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:30.808399Z","time spent":"415.910676ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:40:31.224374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.054018ms","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-14T17:40:31.224264Z","caller":"traceutil/trace.go:171","msg":"trace[2124184285] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1102; }","duration":"397.982206ms","start":"2026-07-14T17:40:30.826265Z","end":"2026-07-14T17:40:31.224247Z","steps":["trace[2124184285] 'agreement among raft nodes before linearized reading' (duration: 397.872323ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:31.224424Z","caller":"traceutil/trace.go:171","msg":"trace[208409264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"246.138301ms","start":"2026-07-14T17:40:30.978274Z","end":"2026-07-14T17:40:31.224412Z","steps":["trace[208409264] 'agreement among raft nodes before linearized reading' (duration: 246.018277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.224507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:30.82625Z","time spent":"398.209663ms","remote":"127.0.0.1:40848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:40:32.327101Z","caller":"traceutil/trace.go:171","msg":"trace[476328141] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"546.760338ms","start":"2026-07-14T17:40:31.780318Z","end":"2026-07-14T17:40:32.327079Z","steps":["trace[476328141] 'process raft request' (duration: 546.625534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:32.327268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:31.780293Z","time spent":"546.871591ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:32.333258Z","caller":"traceutil/trace.go:171","msg":"trace[215850818] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"183.734567ms","start":"2026-07-14T17:40:32.1495Z","end":"2026-07-14T17:40:32.333234Z","steps":["trace[215850818] 'process raft request' (duration: 183.460499ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:33.525658Z","caller":"traceutil/trace.go:171","msg":"trace[1571306104] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"241.905935ms","start":"2026-07-14T17:40:33.283724Z","end":"2026-07-14T17:40:33.52563Z","steps":["trace[1571306104] 'read index received' (duration: 241.73629ms)","trace[1571306104] 'applied index is now lower than readState.Index' (duration: 168.365µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:33.525795Z","caller":"traceutil/trace.go:171","msg":"trace[541825183] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"242.934253ms","start":"2026-07-14T17:40:33.282818Z","end":"2026-07-14T17:40:33.525752Z","steps":["trace[541825183] 'process raft request' (duration: 242.627435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:33.525916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.07765ms","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-14T17:40:33.52597Z","caller":"traceutil/trace.go:171","msg":"trace[323311226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"242.150262ms","start":"2026-07-14T17:40:33.283803Z","end":"2026-07-14T17:40:33.525954Z","steps":["trace[323311226] 'agreement among raft nodes before linearized reading' (duration: 241.979117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:33.525989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.212604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T17:40:33.526054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.18357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:40:33.526094Z","caller":"traceutil/trace.go:171","msg":"trace[9780235] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1110; }","duration":"128.288213ms","start":"2026-07-14T17:40:33.397795Z","end":"2026-07-14T17:40:33.526083Z","steps":["trace[9780235] 'agreement among raft nodes before linearized reading' (duration: 128.190931ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:33.526132Z","caller":"traceutil/trace.go:171","msg":"trace[1144478457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1110; }","duration":"242.43441ms","start":"2026-07-14T17:40:33.283632Z","end":"2026-07-14T17:40:33.526066Z","steps":["trace[1144478457] 'agreement among raft nodes before linearized reading' (duration: 242.133182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:33.526174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.074925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:33.526284Z","caller":"traceutil/trace.go:171","msg":"trace[820313200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"218.212558ms","start":"2026-07-14T17:40:33.308045Z","end":"2026-07-14T17:40:33.526258Z","steps":["trace[820313200] 'agreement among raft nodes before linearized reading' (duration: 218.042964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.000007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.648361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:34.000101Z","caller":"traceutil/trace.go:171","msg":"trace[624107160] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"407.438426ms","start":"2026-07-14T17:40:33.592646Z","end":"2026-07-14T17:40:34.000085Z","steps":["trace[624107160] 'read index received' (duration: 4.509603ms)","trace[624107160] 'applied index is now lower than readState.Index' (duration: 402.927563ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:34.000275Z","caller":"traceutil/trace.go:171","msg":"trace[37871982] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"469.710906ms","start":"2026-07-14T17:40:33.530537Z","end":"2026-07-14T17:40:34.000248Z","steps":["trace[37871982] 'process raft request' (duration: 287.541101ms)","trace[37871982] 'compare' (duration: 181.474586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:34.00017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.528618ms","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":"warn","ts":"2026-07-14T17:40:34.000409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.530525Z","time spent":"469.806118ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:34.000432Z","caller":"traceutil/trace.go:171","msg":"trace[1529333086] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1112; }","duration":"407.780605ms","start":"2026-07-14T17:40:33.592631Z","end":"2026-07-14T17:40:34.000412Z","steps":["trace[1529333086] 'agreement among raft nodes before linearized reading' (duration: 407.495867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.000616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.59257Z","time spent":"407.95468ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:40:49.507199Z","caller":"traceutil/trace.go:171","msg":"trace[1002128725] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"160.453012ms","start":"2026-07-14T17:40:49.34672Z","end":"2026-07-14T17:40:49.507173Z","steps":["trace[1002128725] 'process raft request' (duration: 160.116922ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:51.164736Z","caller":"traceutil/trace.go:171","msg":"trace[1950966914] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"749.434062ms","start":"2026-07-14T17:40:50.415259Z","end":"2026-07-14T17:40:51.164693Z","steps":["trace[1950966914] 'process raft request' (duration: 749.129794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:51.164883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:50.415204Z","time spent":"749.605128ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:51.485355Z","caller":"traceutil/trace.go:171","msg":"trace[1213149729] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"177.744073ms","start":"2026-07-14T17:40:51.307591Z","end":"2026-07-14T17:40:51.485335Z","steps":["trace[1213149729] 'read index received' (duration: 133.66431ms)","trace[1213149729] 'applied index is now lower than readState.Index' (duration: 44.078903ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:51.485394Z","caller":"traceutil/trace.go:171","msg":"trace[51386040] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"316.643106ms","start":"2026-07-14T17:40:51.168712Z","end":"2026-07-14T17:40:51.485355Z","steps":["trace[51386040] 'process raft request' (duration: 272.635974ms)","trace[51386040] 'compare' (duration: 43.891359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:51.485463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.856646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:51.485488Z","caller":"traceutil/trace.go:171","msg":"trace[146294559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"177.922808ms","start":"2026-07-14T17:40:51.307557Z","end":"2026-07-14T17:40:51.48548Z","steps":["trace[146294559] 'agreement among raft nodes before linearized reading' (duration: 177.851386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:51.48555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:51.168688Z","time spent":"316.774149ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:03.314544Z","caller":"traceutil/trace.go:171","msg":"trace[1022766884] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"122.284999ms","start":"2026-07-14T17:41:03.192241Z","end":"2026-07-14T17:41:03.314526Z","steps":["trace[1022766884] 'process raft request' (duration: 122.175346ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.69725Z","caller":"traceutil/trace.go:171","msg":"trace[70953896] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"135.681724ms","start":"2026-07-14T17:41:07.561531Z","end":"2026-07-14T17:41:07.697212Z","steps":["trace[70953896] 'process raft request' (duration: 135.397696ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.820187Z","caller":"traceutil/trace.go:171","msg":"trace[1571415345] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"117.278543ms","start":"2026-07-14T17:41:07.702884Z","end":"2026-07-14T17:41:07.820163Z","steps":["trace[1571415345] 'process raft request' (duration: 116.691147ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.820468Z","caller":"traceutil/trace.go:171","msg":"trace[1640178686] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"116.45387ms","start":"2026-07-14T17:41:07.704002Z","end":"2026-07-14T17:41:07.820456Z","steps":["trace[1640178686] 'process raft request' (duration: 115.681029ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:08.10503Z","caller":"traceutil/trace.go:171","msg":"trace[1172467518] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"152.956477ms","start":"2026-07-14T17:41:07.952051Z","end":"2026-07-14T17:41:08.105007Z","steps":["trace[1172467518] 'process raft request' (duration: 152.904295ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:08.105178Z","caller":"traceutil/trace.go:171","msg":"trace[387811118] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"153.540033ms","start":"2026-07-14T17:41:07.951542Z","end":"2026-07-14T17:41:08.105082Z","steps":["trace[387811118] 'process raft request' (duration: 153.008598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:09.991253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965515317468285470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:41:10.105123Z","caller":"traceutil/trace.go:171","msg":"trace[900877545] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"613.934113ms","start":"2026-07-14T17:41:09.49117Z","end":"2026-07-14T17:41:10.105104Z","steps":["trace[900877545] 'read index received' (duration: 613.804149ms)","trace[900877545] 'applied index is now lower than readState.Index' (duration: 129.144µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:10.105182Z","caller":"traceutil/trace.go:171","msg":"trace[908704604] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"656.426063ms","start":"2026-07-14T17:41:09.44873Z","end":"2026-07-14T17:41:10.105157Z","steps":["trace[908704604] 'process raft request' (duration: 656.264599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.10526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.069546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:10.105293Z","caller":"traceutil/trace.go:171","msg":"trace[709253625] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1296; }","duration":"614.120958ms","start":"2026-07-14T17:41:09.491163Z","end":"2026-07-14T17:41:10.105284Z","steps":["trace[709253625] 'agreement among raft nodes before linearized reading' (duration: 614.041226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.105322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:09.491093Z","time spent":"614.221481ms","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:10.105353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.038871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:41:10.105391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.538915ms","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-14T17:41:10.105403Z","caller":"traceutil/trace.go:171","msg":"trace[418850896] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1296; }","duration":"278.101673ms","start":"2026-07-14T17:41:09.82729Z","end":"2026-07-14T17:41:10.105391Z","steps":["trace[418850896] 'agreement among raft nodes before linearized reading' (duration: 278.00832ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:10.105418Z","caller":"traceutil/trace.go:171","msg":"trace[396867041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"256.606046ms","start":"2026-07-14T17:41:09.848805Z","end":"2026-07-14T17:41:10.105411Z","steps":["trace[396867041] 'agreement among raft nodes before linearized reading' (duration: 256.477983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.105571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.739112ms","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-14T17:41:10.105676Z","caller":"traceutil/trace.go:171","msg":"trace[1800575672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"359.882796ms","start":"2026-07-14T17:41:09.745775Z","end":"2026-07-14T17:41:10.105658Z","steps":["trace[1800575672] 'agreement among raft nodes before linearized reading' (duration: 359.526896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.105765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:09.745761Z","time spent":"359.993809ms","remote":"127.0.0.1:40978","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-14T17:41:10.10579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.592186ms","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-14T17:41:10.105952Z","caller":"traceutil/trace.go:171","msg":"trace[383638667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"278.780801ms","start":"2026-07-14T17:41:09.82714Z","end":"2026-07-14T17:41:10.105921Z","steps":["trace[383638667] 'agreement among raft nodes before linearized reading' (duration: 278.295658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.105648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.243453ms","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-14T17:41:10.10605Z","caller":"traceutil/trace.go:171","msg":"trace[1805044166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"272.699516ms","start":"2026-07-14T17:41:09.833342Z","end":"2026-07-14T17:41:10.106042Z","steps":["trace[1805044166] 'agreement among raft nodes before linearized reading' (duration: 272.019847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.106118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:09.448713Z","time spent":"656.523146ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:10.754827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965515317468285473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:41:10.89437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.390831ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-14T17:41:10.89447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.106475Z","time spent":"787.990656ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-14T17:41:11.172921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005984948s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T17:41:11.267983Z","caller":"traceutil/trace.go:171","msg":"trace[1833779805] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1353; }","duration":"1.013631266s","start":"2026-07-14T17:41:10.254335Z","end":"2026-07-14T17:41:11.267967Z","steps":["trace[1833779805] 'read index received' (duration: 918.870419ms)","trace[1833779805] 'applied index is now lower than readState.Index' (duration: 94.760107ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:11.268059Z","caller":"traceutil/trace.go:171","msg":"trace[251417481] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.157117674s","start":"2026-07-14T17:41:10.110935Z","end":"2026-07-14T17:41:11.268053Z","steps":["trace[251417481] 'process raft request' (duration: 1.156949539s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.268131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.110912Z","time spent":"1.157164536s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:11.268162Z","caller":"traceutil/trace.go:171","msg":"trace[1202143567] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"1.156182578s","start":"2026-07-14T17:41:10.111926Z","end":"2026-07-14T17:41:11.268109Z","steps":["trace[1202143567] 'process raft request' (duration: 1.156013834s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:11.268003Z","caller":"traceutil/trace.go:171","msg":"trace[526206503] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.159112608s","start":"2026-07-14T17:41:10.108873Z","end":"2026-07-14T17:41:11.267985Z","steps":["trace[526206503] 'process raft request' (duration: 1.064324631s)","trace[526206503] 'compare' (duration: 94.16911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:11.268259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.108856Z","time spent":"1.159375116s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:11.268287Z","caller":"traceutil/trace.go:171","msg":"trace[765496180] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.157144195s","start":"2026-07-14T17:41:10.111121Z","end":"2026-07-14T17:41:11.268265Z","steps":["trace[765496180] 'process raft request' (duration: 1.156800035s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.268313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.111917Z","time spent":"1.156310322s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:11.268304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013955345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T17:41:11.26836Z","caller":"traceutil/trace.go:171","msg":"trace[1343454481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.014066598s","start":"2026-07-14T17:41:10.25428Z","end":"2026-07-14T17:41:11.268347Z","steps":["trace[1343454481] 'agreement among raft nodes before linearized reading' (duration: 1.013883933s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.26839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.254249Z","time spent":"1.01413601s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:41:11.268378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.111111Z","time spent":"1.157220007s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:11.363521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056025734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:41:11.363569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.885675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:41:11.363609Z","caller":"traceutil/trace.go:171","msg":"trace[762289184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"1.056166077s","start":"2026-07-14T17:41:10.307424Z","end":"2026-07-14T17:41:11.36359Z","steps":["trace[762289184] 'agreement among raft nodes before linearized reading' (duration: 1.056006873s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:11.363628Z","caller":"traceutil/trace.go:171","msg":"trace[1081768306] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1301; }","duration":"898.026069ms","start":"2026-07-14T17:41:10.465589Z","end":"2026-07-14T17:41:11.363615Z","steps":["trace[1081768306] 'agreement among raft nodes before linearized reading' (duration: 897.859245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.363652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.3074Z","time spent":"1.056243559s","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:41:11.363662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.465569Z","time spent":"898.087631ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:41:11.363517Z","caller":"traceutil/trace.go:171","msg":"trace[1343487198] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"468.167252ms","start":"2026-07-14T17:41:10.89531Z","end":"2026-07-14T17:41:11.363477Z","steps":["trace[1343487198] 'process raft request' (duration: 467.969617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.364044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:10.895196Z","time spent":"468.723937ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:11.498954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.090692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:41:11.49906Z","caller":"traceutil/trace.go:171","msg":"trace[1036338024] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"133.182647ms","start":"2026-07-14T17:41:11.365848Z","end":"2026-07-14T17:41:11.499031Z","steps":["trace[1036338024] 'read index received' (duration: 28.944641ms)","trace[1036338024] 'applied index is now lower than readState.Index' (duration: 104.237246ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:11.499103Z","caller":"traceutil/trace.go:171","msg":"trace[1737307141] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"226.000151ms","start":"2026-07-14T17:41:11.273076Z","end":"2026-07-14T17:41:11.499077Z","steps":["trace[1737307141] 'process raft request' (duration: 121.719614ms)","trace[1737307141] 'compare' (duration: 103.970338ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:11.499188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.212374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:11.499211Z","caller":"traceutil/trace.go:171","msg":"trace[205550812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"127.259345ms","start":"2026-07-14T17:41:11.371942Z","end":"2026-07-14T17:41:11.499202Z","steps":["trace[205550812] 'agreement among raft nodes before linearized reading' (duration: 127.230715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.499159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.31456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-14T17:41:11.49937Z","caller":"traceutil/trace.go:171","msg":"trace[897388400] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1302; }","duration":"133.521386ms","start":"2026-07-14T17:41:11.365837Z","end":"2026-07-14T17:41:11.499358Z","steps":["trace[897388400] 'agreement among raft nodes before linearized reading' (duration: 133.286889ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:12.817156Z","caller":"traceutil/trace.go:171","msg":"trace[1018825820] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"453.372299ms","start":"2026-07-14T17:41:12.363764Z","end":"2026-07-14T17:41:12.817136Z","steps":["trace[1018825820] 'read index received' (duration: 453.230905ms)","trace[1018825820] 'applied index is now lower than readState.Index' (duration: 140.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:12.817218Z","caller":"traceutil/trace.go:171","msg":"trace[2066451069] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"703.586521ms","start":"2026-07-14T17:41:12.113597Z","end":"2026-07-14T17:41:12.817184Z","steps":["trace[2066451069] 'process raft request' (duration: 703.415116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:12.817269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.490453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:12.817296Z","caller":"traceutil/trace.go:171","msg":"trace[730284617] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1303; }","duration":"453.575715ms","start":"2026-07-14T17:41:12.363712Z","end":"2026-07-14T17:41:12.817288Z","steps":["trace[730284617] 'agreement among raft nodes before linearized reading' (duration: 453.509563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:12.817322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.363693Z","time spent":"453.623576ms","remote":"127.0.0.1:40798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:12.817372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.113582Z","time spent":"703.705214ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:13.785085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.820065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T17:41:13.785099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.895137ms","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-14T17:41:13.785143Z","caller":"traceutil/trace.go:171","msg":"trace[1948147811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"511.931648ms","start":"2026-07-14T17:41:13.273198Z","end":"2026-07-14T17:41:13.785129Z","steps":["trace[1948147811] 'range keys from in-memory index tree' (duration: 511.678071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.27317Z","time spent":"511.995879ms","remote":"127.0.0.1:40978","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-14T17:41:13.78518Z","caller":"traceutil/trace.go:171","msg":"trace[1654426819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"512.02647ms","start":"2026-07-14T17:41:13.273132Z","end":"2026-07-14T17:41:13.785159Z","steps":["trace[1654426819] 'range keys from in-memory index tree' (duration: 511.708352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.273103Z","time spent":"512.112133ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:41:13.785337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.907117ms","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-14T17:41:13.785362Z","caller":"traceutil/trace.go:171","msg":"trace[725570513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"511.952739ms","start":"2026-07-14T17:41:13.273403Z","end":"2026-07-14T17:41:13.785355Z","steps":["trace[725570513] 'range keys from in-memory index tree' (duration: 511.810565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.273387Z","time spent":"511.987919ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:41:13.785456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.01009ms","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-14T17:41:13.785377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.566355ms","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-14T17:41:13.785479Z","caller":"traceutil/trace.go:171","msg":"trace[1248755136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"512.065722ms","start":"2026-07-14T17:41:13.273408Z","end":"2026-07-14T17:41:13.785474Z","steps":["trace[1248755136] 'range keys from in-memory index tree' (duration: 511.900158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.273375Z","time spent":"512.117473ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:41:13.785508Z","caller":"traceutil/trace.go:171","msg":"trace[1333426862] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1303; }","duration":"731.724629ms","start":"2026-07-14T17:41:13.053763Z","end":"2026-07-14T17:41:13.785488Z","steps":["trace[1333426862] 'range keys from in-memory index tree' (duration: 731.536024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.818331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T17:41:13.785593Z","caller":"traceutil/trace.go:171","msg":"trace[1904275682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"282.873273ms","start":"2026-07-14T17:41:13.502715Z","end":"2026-07-14T17:41:13.785588Z","steps":["trace[1904275682] 'range keys from in-memory index tree' (duration: 282.686209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.364235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:13.785649Z","caller":"traceutil/trace.go:171","msg":"trace[1707916275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"477.483907ms","start":"2026-07-14T17:41:13.308146Z","end":"2026-07-14T17:41:13.78563Z","steps":["trace[1707916275] 'range keys from in-memory index tree' (duration: 477.279392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.785694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.308133Z","time spent":"477.55308ms","remote":"127.0.0.1:40692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:41:13.889849Z","caller":"traceutil/trace.go:171","msg":"trace[490576227] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"268.750088ms","start":"2026-07-14T17:41:13.621077Z","end":"2026-07-14T17:41:13.889827Z","steps":["trace[490576227] 'process raft request' (duration: 268.614905ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.889858Z","caller":"traceutil/trace.go:171","msg":"trace[763603337] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"102.431707ms","start":"2026-07-14T17:41:13.787393Z","end":"2026-07-14T17:41:13.889825Z","steps":["trace[763603337] 'read index received' (duration: 102.411137ms)","trace[763603337] 'applied index is now lower than readState.Index' (duration: 16.87µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:13.89021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.567871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:13.890286Z","caller":"traceutil/trace.go:171","msg":"trace[199613692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"102.912441ms","start":"2026-07-14T17:41:13.787362Z","end":"2026-07-14T17:41:13.890274Z","steps":["trace[199613692] 'agreement among raft nodes before linearized reading' (duration: 102.561751ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.139822Z","caller":"traceutil/trace.go:171","msg":"trace[740699415] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"349.619746ms","start":"2026-07-14T17:41:13.790167Z","end":"2026-07-14T17:41:14.139787Z","steps":["trace[740699415] 'process raft request' (duration: 336.460397ms)","trace[740699415] 'compare' (duration: 12.869171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:14.140047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.790153Z","time spent":"349.78741ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:14.140431Z","caller":"traceutil/trace.go:171","msg":"trace[1908210066] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"350.162191ms","start":"2026-07-14T17:41:13.790234Z","end":"2026-07-14T17:41:14.140396Z","steps":["trace[1908210066] 'process raft request' (duration: 349.482702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.140625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.790227Z","time spent":"350.305135ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:14.141012Z","caller":"traceutil/trace.go:171","msg":"trace[1445780509] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"350.762607ms","start":"2026-07-14T17:41:13.79023Z","end":"2026-07-14T17:41:14.140993Z","steps":["trace[1445780509] 'process raft request' (duration: 350.006607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.141109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.790209Z","time spent":"350.85358ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:14.14149Z","caller":"traceutil/trace.go:171","msg":"trace[1902078897] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"350.882961ms","start":"2026-07-14T17:41:13.790591Z","end":"2026-07-14T17:41:14.141474Z","steps":["trace[1902078897] 'process raft request' (duration: 349.948146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.141585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.79058Z","time spent":"350.958693ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:14.141833Z","caller":"traceutil/trace.go:171","msg":"trace[1524471240] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"350.684145ms","start":"2026-07-14T17:41:13.791115Z","end":"2026-07-14T17:41:14.141799Z","steps":["trace[1524471240] 'process raft request' (duration: 350.338026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.141922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.791103Z","time spent":"350.777548ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:14.142082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.619504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:14.142132Z","caller":"traceutil/trace.go:171","msg":"trace[1854156420] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1361; }","duration":"242.118661ms","start":"2026-07-14T17:41:13.899445Z","end":"2026-07-14T17:41:14.141563Z","steps":["trace[1854156420] 'read index received' (duration: 227.140462ms)","trace[1854156420] 'applied index is now lower than readState.Index' (duration: 14.976329ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:14.142282Z","caller":"traceutil/trace.go:171","msg":"trace[640463137] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1309; }","duration":"242.725208ms","start":"2026-07-14T17:41:13.899415Z","end":"2026-07-14T17:41:14.14214Z","steps":["trace[640463137] 'agreement among raft nodes before linearized reading' (duration: 242.530132ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.955669Z","caller":"traceutil/trace.go:171","msg":"trace[1204892307] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"133.578467ms","start":"2026-07-14T17:41:14.822064Z","end":"2026-07-14T17:41:14.955642Z","steps":["trace[1204892307] 'process raft request' (duration: 133.391082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:15.783552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.700589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:41:15.783688Z","caller":"traceutil/trace.go:171","msg":"trace[2094238988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1311; }","duration":"475.920135ms","start":"2026-07-14T17:41:15.307744Z","end":"2026-07-14T17:41:15.783664Z","steps":["trace[2094238988] 'count revisions from in-memory index tree' (duration: 475.580166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:15.783743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:15.307719Z","time spent":"476.011027ms","remote":"127.0.0.1:40840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:15.783613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.482692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:15.783867Z","caller":"traceutil/trace.go:171","msg":"trace[701073207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"475.760151ms","start":"2026-07-14T17:41:15.308086Z","end":"2026-07-14T17:41:15.783847Z","steps":["trace[701073207] 'range keys from in-memory index tree' (duration: 475.399631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:15.783921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:15.30808Z","time spent":"475.830512ms","remote":"127.0.0.1:40692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:41:16.032307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.493243ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T17:41:16.032548Z","caller":"traceutil/trace.go:171","msg":"trace[1652415315] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"246.898612ms","start":"2026-07-14T17:41:15.785631Z","end":"2026-07-14T17:41:16.032529Z","steps":["trace[1652415315] 'read index received' (duration: 76.550221ms)","trace[1652415315] 'applied index is now lower than readState.Index' (duration: 170.347341ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:16.032644Z","caller":"traceutil/trace.go:171","msg":"trace[1357641733] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"401.598705ms","start":"2026-07-14T17:41:15.631032Z","end":"2026-07-14T17:41:16.03263Z","steps":["trace[1357641733] 'process raft request' (duration: 401.377769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.032789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:15.631009Z","time spent":"401.668198ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:16.032831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.180799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:16.03291Z","caller":"traceutil/trace.go:171","msg":"trace[609821213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"247.302553ms","start":"2026-07-14T17:41:15.785588Z","end":"2026-07-14T17:41:16.032891Z","steps":["trace[609821213] 'agreement among raft nodes before linearized reading' (duration: 247.121368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.033018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.374804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:41:16.033136Z","caller":"traceutil/trace.go:171","msg":"trace[1542826785] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1312; }","duration":"121.543839ms","start":"2026-07-14T17:41:15.911576Z","end":"2026-07-14T17:41:16.03312Z","steps":["trace[1542826785] 'agreement among raft nodes before linearized reading' (duration: 121.084026ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.284248Z","caller":"traceutil/trace.go:171","msg":"trace[1036468756] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"138.696197ms","start":"2026-07-14T17:41:16.145522Z","end":"2026-07-14T17:41:16.284218Z","steps":["trace[1036468756] 'read index received' (duration: 67.962956ms)","trace[1036468756] 'applied index is now lower than readState.Index' (duration: 70.730581ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:16.284295Z","caller":"traceutil/trace.go:171","msg":"trace[1222359060] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"140.769824ms","start":"2026-07-14T17:41:16.143484Z","end":"2026-07-14T17:41:16.284254Z","steps":["trace[1222359060] 'process raft request' (duration: 69.890719ms)","trace[1222359060] 'compare' (duration: 70.577417ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:16.284626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.404692ms","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-14T17:41:16.284648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.321127ms","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-14T17:41:16.284723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.715131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T17:41:16.284716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.636318ms","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-14T17:41:16.284778Z","caller":"traceutil/trace.go:171","msg":"trace[1100370337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"137.758102ms","start":"2026-07-14T17:41:16.146989Z","end":"2026-07-14T17:41:16.284747Z","steps":["trace[1100370337] 'agreement among raft nodes before linearized reading' (duration: 137.403812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.284808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.281783ms","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-14T17:41:16.284844Z","caller":"traceutil/trace.go:171","msg":"trace[372787369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"139.344845ms","start":"2026-07-14T17:41:16.145491Z","end":"2026-07-14T17:41:16.284836Z","steps":["trace[372787369] 'agreement among raft nodes before linearized reading' (duration: 138.824871ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.284852Z","caller":"traceutil/trace.go:171","msg":"trace[1611135219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"137.790912ms","start":"2026-07-14T17:41:16.147002Z","end":"2026-07-14T17:41:16.284793Z","steps":["trace[1611135219] 'agreement among raft nodes before linearized reading' (duration: 137.530555ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.284721Z","caller":"traceutil/trace.go:171","msg":"trace[1501787536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"134.42159ms","start":"2026-07-14T17:41:16.150282Z","end":"2026-07-14T17:41:16.284703Z","steps":["trace[1501787536] 'agreement among raft nodes before linearized reading' (duration: 134.168584ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.28467Z","caller":"traceutil/trace.go:171","msg":"trace[238470642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"137.470303ms","start":"2026-07-14T17:41:16.147189Z","end":"2026-07-14T17:41:16.284659Z","steps":["trace[238470642] 'agreement among raft nodes before linearized reading' (duration: 137.34909ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:21.217435Z","caller":"traceutil/trace.go:171","msg":"trace[463340640] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"206.348234ms","start":"2026-07-14T17:41:21.011061Z","end":"2026-07-14T17:41:21.21741Z","steps":["trace[463340640] 'process raft request' (duration: 206.19325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:21.512058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.939731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:21.512152Z","caller":"traceutil/trace.go:171","msg":"trace[1375367188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"203.094236ms","start":"2026-07-14T17:41:21.309038Z","end":"2026-07-14T17:41:21.512132Z","steps":["trace[1375367188] 'range keys from in-memory index tree' (duration: 202.837099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:21.512091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.260908ms","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-14T17:41:21.512304Z","caller":"traceutil/trace.go:171","msg":"trace[729802442] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1351; }","duration":"137.496645ms","start":"2026-07-14T17:41:21.374757Z","end":"2026-07-14T17:41:21.512254Z","steps":["trace[729802442] 'range keys from in-memory index tree' (duration: 136.94566ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.582911Z","caller":"traceutil/trace.go:171","msg":"trace[1898901286] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"123.477972ms","start":"2026-07-14T17:41:28.459403Z","end":"2026-07-14T17:41:28.582881Z","steps":["trace[1898901286] 'process raft request' (duration: 96.175637ms)","trace[1898901286] 'compare' (duration: 27.11801ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:28.583268Z","caller":"traceutil/trace.go:171","msg":"trace[270125518] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1459; }","duration":"117.760065ms","start":"2026-07-14T17:41:28.465486Z","end":"2026-07-14T17:41:28.583246Z","steps":["trace[270125518] 'read index received' (duration: 27.659765ms)","trace[270125518] 'applied index is now lower than readState.Index' (duration: 90.070929ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:28.583476Z","caller":"traceutil/trace.go:171","msg":"trace[572130341] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"122.991178ms","start":"2026-07-14T17:41:28.460458Z","end":"2026-07-14T17:41:28.583449Z","steps":["trace[572130341] 'process raft request' (duration: 122.67694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.583517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.004652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:28.583563Z","caller":"traceutil/trace.go:171","msg":"trace[1668618178] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1403; }","duration":"118.090034ms","start":"2026-07-14T17:41:28.465459Z","end":"2026-07-14T17:41:28.583549Z","steps":["trace[1668618178] 'agreement among raft nodes before linearized reading' (duration: 117.996462ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.583826Z","caller":"traceutil/trace.go:171","msg":"trace[581891135] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"123.464381ms","start":"2026-07-14T17:41:28.460338Z","end":"2026-07-14T17:41:28.583802Z","steps":["trace[581891135] 'process raft request' (duration: 122.506445ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:44.71183Z","caller":"traceutil/trace.go:171","msg":"trace[777772754] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"145.800971ms","start":"2026-07-14T17:41:44.565997Z","end":"2026-07-14T17:41:44.711798Z","steps":["trace[777772754] 'process raft request' (duration: 46.087239ms)","trace[777772754] 'compare' (duration: 99.605419ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:06.767676Z","caller":"traceutil/trace.go:171","msg":"trace[1474896686] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"119.15189ms","start":"2026-07-14T17:42:06.648483Z","end":"2026-07-14T17:42:06.767635Z","steps":["trace[1474896686] 'process raft request' (duration: 94.610648ms)","trace[1474896686] 'compare' (duration: 24.387987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:45:40.371766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.695579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-14T17:45:40.371851Z","caller":"traceutil/trace.go:171","msg":"trace[1914327982] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2584; }","duration":"108.830652ms","start":"2026-07-14T17:45:40.263003Z","end":"2026-07-14T17:45:40.371833Z","steps":["trace[1914327982] 'agreement among raft nodes before linearized reading' (duration: 84.574547ms)","trace[1914327982] 'range keys from in-memory index tree' (duration: 24.131082ms)"],"step_count":2}