{"level":"warn","ts":"2026-04-15T12:17:12.780237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T12:17:12.780361Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-04-15T12:17:12.780446Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T12:17:12.780465Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-15T12:17:12.780491Z","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-04-15T12:17:12.781655Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-15T12:17:12.781791Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-04-15T12:17:12.786327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.049537ms"} {"level":"info","ts":"2026-04-15T12:17:12.794892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-15T12:17:12.795006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T12:17:12.795048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-15T12:17:12.795068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T12:17:12.795092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-15T12:17:12.79516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-15T12:17:12.80364Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T12:17:12.805622Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T12:17:12.811735Z","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-04-15T12:17:12.815089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T12:17:12.816974Z","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-04-15T12:17:12.817103Z","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-04-15T12:17:12.81712Z","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-04-15T12:17:12.817065Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T12:17:12.818054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-15T12:17:12.818236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-15T12:17:12.819008Z","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-04-15T12:17:12.819095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-15T12:17:12.81913Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-15T12:17:12.819396Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T12:17:12.819435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T12:17:13.395981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T12:17:13.396053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T12:17:13.396107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-15T12:17:13.396135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-15T12:17:13.396153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-15T12:17:13.396182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-15T12:17:13.396202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-15T12:17:13.399838Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T12:17:13.39987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T12:17:13.400152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T12:17:13.400331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T12:17:13.400324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T12:17:13.400414Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T12:17:13.402981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T12:17:13.424839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-15T12:17:13.427648Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T12:17:13.427909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T12:17:13.427967Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T12:17:15.028835Z","caller":"traceutil/trace.go:171","msg":"trace[1321087386] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"103.234046ms","start":"2026-04-15T12:17:14.925563Z","end":"2026-04-15T12:17:15.028797Z","steps":["trace[1321087386] 'process raft request' (duration: 102.966719ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.028941Z","caller":"traceutil/trace.go:171","msg":"trace[1835798454] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:21; }","duration":"101.132831ms","start":"2026-04-15T12:17:14.927776Z","end":"2026-04-15T12:17:15.028909Z","steps":["trace[1835798454] 'read index received' (duration: 12.307872ms)","trace[1835798454] 'applied index is now lower than readState.Index' (duration: 88.823229ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:15.029018Z","caller":"traceutil/trace.go:171","msg":"trace[1552428171] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"103.491192ms","start":"2026-04-15T12:17:14.925488Z","end":"2026-04-15T12:17:15.028979Z","steps":["trace[1552428171] 'process raft request' (duration: 40.58083ms)","trace[1552428171] 'compare' (duration: 62.257926ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:15.029119Z","caller":"traceutil/trace.go:171","msg":"trace[957903165] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"102.826144ms","start":"2026-04-15T12:17:14.926279Z","end":"2026-04-15T12:17:15.029105Z","steps":["trace[957903165] 'process raft request' (duration: 102.31115ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.029241Z","caller":"traceutil/trace.go:171","msg":"trace[1601792459] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"101.789507ms","start":"2026-04-15T12:17:14.927409Z","end":"2026-04-15T12:17:15.029199Z","steps":["trace[1601792459] 'process raft request' (duration: 101.407737ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.029403Z","caller":"traceutil/trace.go:171","msg":"trace[1197084954] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"102.949148ms","start":"2026-04-15T12:17:14.926426Z","end":"2026-04-15T12:17:15.029375Z","steps":["trace[1197084954] 'process raft request' (duration: 102.205438ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.029591Z","caller":"traceutil/trace.go:171","msg":"trace[2128622402] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"102.423364ms","start":"2026-04-15T12:17:14.92715Z","end":"2026-04-15T12:17:15.029573Z","steps":["trace[2128622402] 'process raft request' (duration: 101.583192ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.029703Z","caller":"traceutil/trace.go:171","msg":"trace[2036111131] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"101.605003ms","start":"2026-04-15T12:17:14.928066Z","end":"2026-04-15T12:17:15.029671Z","steps":["trace[2036111131] 'process raft request' (duration: 100.787841ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.029721Z","caller":"traceutil/trace.go:171","msg":"trace[1922533632] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"102.391304ms","start":"2026-04-15T12:17:14.927317Z","end":"2026-04-15T12:17:15.029708Z","steps":["trace[1922533632] 'process raft request' (duration: 101.47325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:15.030184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.030124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T12:17:15.030249Z","caller":"traceutil/trace.go:171","msg":"trace[2020844114] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:26; }","duration":"102.140358ms","start":"2026-04-15T12:17:14.928094Z","end":"2026-04-15T12:17:15.030234Z","steps":["trace[2020844114] 'agreement among raft nodes before linearized reading' (duration: 101.300005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:15.031117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.319838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T12:17:15.031178Z","caller":"traceutil/trace.go:171","msg":"trace[1939322566] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:26; }","duration":"103.39912ms","start":"2026-04-15T12:17:14.927763Z","end":"2026-04-15T12:17:15.031162Z","steps":["trace[1939322566] 'agreement among raft nodes before linearized reading' (duration: 101.756046ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.470315Z","caller":"traceutil/trace.go:171","msg":"trace[1121623243] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"109.91271ms","start":"2026-04-15T12:17:15.360371Z","end":"2026-04-15T12:17:15.470284Z","steps":["trace[1121623243] 'process raft request' (duration: 70.520551ms)","trace[1121623243] 'compare' (duration: 39.160883ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:15.658577Z","caller":"traceutil/trace.go:171","msg":"trace[1586670036] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"100.556086ms","start":"2026-04-15T12:17:15.557987Z","end":"2026-04-15T12:17:15.658544Z","steps":["trace[1586670036] 'process raft request' (duration: 13.264207ms)","trace[1586670036] 'compare' (duration: 87.105094ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:15.837016Z","caller":"traceutil/trace.go:171","msg":"trace[1681636774] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"117.300293ms","start":"2026-04-15T12:17:15.719676Z","end":"2026-04-15T12:17:15.836976Z","steps":["trace[1681636774] 'process raft request' (duration: 50.632032ms)","trace[1681636774] 'compare' (duration: 66.401334ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:15.837197Z","caller":"traceutil/trace.go:171","msg":"trace[1368684934] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"106.252835ms","start":"2026-04-15T12:17:15.730762Z","end":"2026-04-15T12:17:15.837015Z","steps":["trace[1368684934] 'process raft request' (duration: 106.128132ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:15.837212Z","caller":"traceutil/trace.go:171","msg":"trace[507345581] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:68; }","duration":"106.279005ms","start":"2026-04-15T12:17:15.730906Z","end":"2026-04-15T12:17:15.837185Z","steps":["trace[507345581] 'read index received' (duration: 39.419209ms)","trace[507345581] 'applied index is now lower than readState.Index' (duration: 66.670991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:15.837253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.316466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T12:17:15.83731Z","caller":"traceutil/trace.go:171","msg":"trace[909828855] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:66; }","duration":"106.433189ms","start":"2026-04-15T12:17:15.730862Z","end":"2026-04-15T12:17:15.837296Z","steps":["trace[909828855] 'agreement among raft nodes before linearized reading' (duration: 106.271515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:15.837384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.333187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T12:17:15.837872Z","caller":"traceutil/trace.go:171","msg":"trace[386624582] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:66; }","duration":"106.897321ms","start":"2026-04-15T12:17:15.730958Z","end":"2026-04-15T12:17:15.837855Z","steps":["trace[386624582] 'agreement among raft nodes before linearized reading' (duration: 106.374098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:38.257971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:37.792511Z","time spent":"465.456884ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-15T12:17:44.187607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603224926,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T12:17:44.201172Z","caller":"traceutil/trace.go:171","msg":"trace[1692967270] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"770.712855ms","start":"2026-04-15T12:17:43.430433Z","end":"2026-04-15T12:17:44.201146Z","steps":["trace[1692967270] 'process raft request' (duration: 770.561491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.201648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.430397Z","time spent":"770.830228ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211282Z","caller":"traceutil/trace.go:171","msg":"trace[1659574961] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"775.808828ms","start":"2026-04-15T12:17:43.435449Z","end":"2026-04-15T12:17:44.211258Z","steps":["trace[1659574961] 'process raft request' (duration: 775.657555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.211382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.435416Z","time spent":"775.915431ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211474Z","caller":"traceutil/trace.go:171","msg":"trace[1166951662] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"765.559501ms","start":"2026-04-15T12:17:43.445904Z","end":"2026-04-15T12:17:44.211464Z","steps":["trace[1166951662] 'process raft request' (duration: 765.341595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.211521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.445845Z","time spent":"765.655113ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211543Z","caller":"traceutil/trace.go:171","msg":"trace[1065595409] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"755.929618ms","start":"2026-04-15T12:17:43.455582Z","end":"2026-04-15T12:17:44.211511Z","steps":["trace[1065595409] 'process raft request' (duration: 755.693022ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:44.21159Z","caller":"traceutil/trace.go:171","msg":"trace[17549153] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"765.793956ms","start":"2026-04-15T12:17:43.445755Z","end":"2026-04-15T12:17:44.211549Z","steps":["trace[17549153] 'process raft request' (duration: 765.461197ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:44.211621Z","caller":"traceutil/trace.go:171","msg":"trace[277514024] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"745.111496ms","start":"2026-04-15T12:17:43.466501Z","end":"2026-04-15T12:17:44.211612Z","steps":["trace[277514024] 'process raft request' (duration: 744.799548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.211661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.455555Z","time spent":"756.052171ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:44.21167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.466476Z","time spent":"745.175039ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:44.211717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.445745Z","time spent":"765.91036ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.21177Z","caller":"traceutil/trace.go:171","msg":"trace[1533790281] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"732.517798ms","start":"2026-04-15T12:17:43.479244Z","end":"2026-04-15T12:17:44.211762Z","steps":["trace[1533790281] 'process raft request' (duration: 732.260871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.211814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.479219Z","time spent":"732.5746ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211878Z","caller":"traceutil/trace.go:171","msg":"trace[1802388283] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"745.288971ms","start":"2026-04-15T12:17:43.466579Z","end":"2026-04-15T12:17:44.211868Z","steps":["trace[1802388283] 'process raft request' (duration: 744.8854ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:44.211891Z","caller":"traceutil/trace.go:171","msg":"trace[1296971334] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:532; }","duration":"524.719912ms","start":"2026-04-15T12:17:43.687156Z","end":"2026-04-15T12:17:44.211875Z","steps":["trace[1296971334] 'read index received' (duration: 513.984022ms)","trace[1296971334] 'applied index is now lower than readState.Index' (duration: 10.73505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:44.211919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.466574Z","time spent":"745.328112ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211928Z","caller":"traceutil/trace.go:171","msg":"trace[162771194] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"727.668961ms","start":"2026-04-15T12:17:43.484247Z","end":"2026-04-15T12:17:44.211916Z","steps":["trace[162771194] 'process raft request' (duration: 727.311981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.211966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.484239Z","time spent":"727.709672ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:44.211995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.841236ms","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-04-15T12:17:44.212Z","caller":"traceutil/trace.go:171","msg":"trace[751889421] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"596.515016ms","start":"2026-04-15T12:17:43.61548Z","end":"2026-04-15T12:17:44.211995Z","steps":["trace[751889421] 'process raft request' (duration: 596.313971ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:44.211937Z","caller":"traceutil/trace.go:171","msg":"trace[1852590438] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"701.9353ms","start":"2026-04-15T12:17:43.509982Z","end":"2026-04-15T12:17:44.211918Z","steps":["trace[1852590438] 'process raft request' (duration: 701.698984ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:44.212024Z","caller":"traceutil/trace.go:171","msg":"trace[339264010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"524.935168ms","start":"2026-04-15T12:17:43.687082Z","end":"2026-04-15T12:17:44.212017Z","steps":["trace[339264010] 'agreement among raft nodes before linearized reading' (duration: 524.852706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.212036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.615447Z","time spent":"596.575688ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:44.212047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.687055Z","time spent":"524.98556ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T12:17:44.212108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.509958Z","time spent":"702.076173ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:44.211319Z","caller":"traceutil/trace.go:171","msg":"trace[1563150678] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"775.789608ms","start":"2026-04-15T12:17:43.435507Z","end":"2026-04-15T12:17:44.211297Z","steps":["trace[1563150678] 'process raft request' (duration: 775.679366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:44.212575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:43.43547Z","time spent":"777.011281ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:45.564944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.216355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:17:45.565027Z","caller":"traceutil/trace.go:171","msg":"trace[1793217759] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.16340735s","start":"2026-04-15T12:17:44.401605Z","end":"2026-04-15T12:17:45.565013Z","steps":["trace[1793217759] 'process raft request' (duration: 169.876946ms)","trace[1793217759] 'compare' (duration: 993.130023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:45.565071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:44.401576Z","time spent":"1.163476202s","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:17:45.618016Z","caller":"traceutil/trace.go:171","msg":"trace[1749268184] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:574; }","duration":"1.077568408s","start":"2026-04-15T12:17:44.540429Z","end":"2026-04-15T12:17:45.617997Z","steps":["trace[1749268184] 'read index received' (duration: 31.115293ms)","trace[1749268184] 'applied index is now lower than readState.Index' (duration: 1.046452545s)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:45.618077Z","caller":"traceutil/trace.go:171","msg":"trace[1400762614] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.195417075s","start":"2026-04-15T12:17:44.422647Z","end":"2026-04-15T12:17:45.618064Z","steps":["trace[1400762614] 'process raft request' (duration: 1.195165728s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:45.618087Z","caller":"traceutil/trace.go:171","msg":"trace[309860069] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.175883486s","start":"2026-04-15T12:17:44.442198Z","end":"2026-04-15T12:17:45.618081Z","steps":["trace[309860069] 'process raft request' (duration: 1.175749411s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:45.61814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:44.422616Z","time spent":"1.195486677s","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:17:45.618154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:44.442176Z","time spent":"1.175926446s","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:45.618193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077746673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:45.618298Z","caller":"traceutil/trace.go:171","msg":"trace[491070746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"1.077889306s","start":"2026-04-15T12:17:44.540372Z","end":"2026-04-15T12:17:45.618262Z","steps":["trace[491070746] 'agreement among raft nodes before linearized reading' (duration: 1.077722952s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:45.618368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:44.540349Z","time spent":"1.07800858s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T12:17:45.642344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.589107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:45.642373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.996832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-15T12:17:45.642403Z","caller":"traceutil/trace.go:171","msg":"trace[434304876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"339.660819ms","start":"2026-04-15T12:17:45.302726Z","end":"2026-04-15T12:17:45.642387Z","steps":["trace[434304876] 'agreement among raft nodes before linearized reading' (duration: 339.507735ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:45.642418Z","caller":"traceutil/trace.go:171","msg":"trace[1940017000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"375.075844ms","start":"2026-04-15T12:17:45.267332Z","end":"2026-04-15T12:17:45.642408Z","steps":["trace[1940017000] 'agreement among raft nodes before linearized reading' (duration: 374.955801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:45.642443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:45.267314Z","time spent":"375.125626ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T12:17:45.642438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:45.302717Z","time spent":"339.71339ms","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T12:17:45.642427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.852528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:17:45.642484Z","caller":"traceutil/trace.go:171","msg":"trace[403490417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"364.939989ms","start":"2026-04-15T12:17:45.27753Z","end":"2026-04-15T12:17:45.64247Z","steps":["trace[403490417] 'agreement among raft nodes before linearized reading' (duration: 364.733344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:45.642515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:45.277515Z","time spent":"364.992711ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T12:17:45.642733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.808642ms","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-04-15T12:17:45.64277Z","caller":"traceutil/trace.go:171","msg":"trace[1223833658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"908.915174ms","start":"2026-04-15T12:17:44.733846Z","end":"2026-04-15T12:17:45.642761Z","steps":["trace[1223833658] 'agreement among raft nodes before linearized reading' (duration: 908.37835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:45.642795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:44.733825Z","time spent":"908.964855ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T12:17:45.76893Z","caller":"traceutil/trace.go:171","msg":"trace[1318137246] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"102.139297ms","start":"2026-04-15T12:17:45.666752Z","end":"2026-04-15T12:17:45.768891Z","steps":["trace[1318137246] 'process raft request' (duration: 101.768288ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:48.090744Z","caller":"traceutil/trace.go:171","msg":"trace[730479930] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"358.212655ms","start":"2026-04-15T12:17:47.732495Z","end":"2026-04-15T12:17:48.090707Z","steps":["trace[730479930] 'process raft request' (duration: 357.982729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:48.090983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:47.732483Z","time spent":"358.381149ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:49.099831Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009351317s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-15T12:17:49.6618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.289997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:17:49.662224Z","caller":"traceutil/trace.go:171","msg":"trace[953537313] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"1.870337109s","start":"2026-04-15T12:17:47.791873Z","end":"2026-04-15T12:17:49.66221Z","steps":["trace[953537313] 'read index received' (duration: 298.71275ms)","trace[953537313] 'applied index is now lower than readState.Index' (duration: 1.571623509s)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:49.662211Z","caller":"traceutil/trace.go:171","msg":"trace[1021693434] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.929585197s","start":"2026-04-15T12:17:47.73259Z","end":"2026-04-15T12:17:49.662176Z","steps":["trace[1021693434] 'process raft request' (duration: 1.367402927s)","trace[1021693434] 'compare' (duration: 561.047761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:49.662318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870440282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-15T12:17:49.662302Z","caller":"traceutil/trace.go:171","msg":"trace[1802897889] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.929459794s","start":"2026-04-15T12:17:47.732813Z","end":"2026-04-15T12:17:49.662273Z","steps":["trace[1802897889] 'process raft request' (duration: 1.92930603s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:49.662328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:47.732574Z","time spent":"1.92969248s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:17:49.662341Z","caller":"traceutil/trace.go:171","msg":"trace[2015313323] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:597; }","duration":"1.870505454s","start":"2026-04-15T12:17:47.791827Z","end":"2026-04-15T12:17:49.662333Z","steps":["trace[2015313323] 'agreement among raft nodes before linearized reading' (duration: 1.870457913s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:49.662385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:47.791807Z","time spent":"1.870553606s","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-04-15T12:17:49.662394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:47.732803Z","time spent":"1.929543717s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:49.662424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358059213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:49.662413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535517137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:49.662494Z","caller":"traceutil/trace.go:171","msg":"trace[1167522593] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:597; }","duration":"1.535691181s","start":"2026-04-15T12:17:48.126784Z","end":"2026-04-15T12:17:49.662475Z","steps":["trace[1167522593] 'agreement among raft nodes before linearized reading' (duration: 1.535565958s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:49.662534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:48.126753Z","time spent":"1.535772163s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-04-15T12:17:49.662448Z","caller":"traceutil/trace.go:171","msg":"trace[215307363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.358124745s","start":"2026-04-15T12:17:48.304314Z","end":"2026-04-15T12:17:49.662439Z","steps":["trace[215307363] 'agreement among raft nodes before linearized reading' (duration: 1.358087914s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:49.662553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415550264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-15T12:17:49.662565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:48.304291Z","time spent":"1.358269058s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T12:17:49.662585Z","caller":"traceutil/trace.go:171","msg":"trace[756922509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.415634747s","start":"2026-04-15T12:17:48.246939Z","end":"2026-04-15T12:17:49.662574Z","steps":["trace[756922509] 'agreement among raft nodes before linearized reading' (duration: 1.415514954s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:49.662614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:48.246918Z","time spent":"1.415689478s","remote":"127.0.0.1:51888","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-04-15T12:17:53.522947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.507055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.523044Z","caller":"traceutil/trace.go:171","msg":"trace[1879547001] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:622; }","duration":"208.620838ms","start":"2026-04-15T12:17:53.314406Z","end":"2026-04-15T12:17:53.523027Z","steps":["trace[1879547001] 'count revisions from in-memory index tree' (duration: 208.475484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.522978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.578167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.523203Z","caller":"traceutil/trace.go:171","msg":"trace[1191521998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:622; }","duration":"208.819672ms","start":"2026-04-15T12:17:53.314343Z","end":"2026-04-15T12:17:53.523163Z","steps":["trace[1191521998] 'range keys from in-memory index tree' (duration: 208.487704ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:53.523153Z","caller":"traceutil/trace.go:171","msg":"trace[610806054] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"184.525358ms","start":"2026-04-15T12:17:53.338604Z","end":"2026-04-15T12:17:53.523129Z","steps":["trace[610806054] 'process raft request' (duration: 181.498198ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:53.553635Z","caller":"traceutil/trace.go:171","msg":"trace[1418057160] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"188.381909ms","start":"2026-04-15T12:17:53.36523Z","end":"2026-04-15T12:17:53.553612Z","steps":["trace[1418057160] 'read index received' (duration: 154.881935ms)","trace[1418057160] 'applied index is now lower than readState.Index' (duration: 33.498664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:53.554134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.137331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.554278Z","caller":"traceutil/trace.go:171","msg":"trace[1543826421] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"137.295536ms","start":"2026-04-15T12:17:53.416944Z","end":"2026-04-15T12:17:53.554239Z","steps":["trace[1543826421] 'agreement among raft nodes before linearized reading' (duration: 137.110541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.554355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.163353ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.554409Z","caller":"traceutil/trace.go:171","msg":"trace[204551091] 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:624; }","duration":"111.251855ms","start":"2026-04-15T12:17:53.443137Z","end":"2026-04-15T12:17:53.554389Z","steps":["trace[204551091] 'agreement among raft nodes before linearized reading' (duration: 111.195204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.554489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.301835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.554584Z","caller":"traceutil/trace.go:171","msg":"trace[911591603] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"137.419819ms","start":"2026-04-15T12:17:53.417134Z","end":"2026-04-15T12:17:53.554554Z","steps":["trace[911591603] 'agreement among raft nodes before linearized reading' (duration: 137.261005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.554378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.12632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.55463Z","caller":"traceutil/trace.go:171","msg":"trace[2005951565] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"189.409356ms","start":"2026-04-15T12:17:53.365204Z","end":"2026-04-15T12:17:53.554614Z","steps":["trace[2005951565] 'agreement among raft nodes before linearized reading' (duration: 189.132149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.55467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.675303ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.554701Z","caller":"traceutil/trace.go:171","msg":"trace[1791966218] 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:624; }","duration":"112.722154ms","start":"2026-04-15T12:17:53.441967Z","end":"2026-04-15T12:17:53.554689Z","steps":["trace[1791966218] 'agreement among raft nodes before linearized reading' (duration: 112.681123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.554294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.169259ms","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-04-15T12:17:53.554896Z","caller":"traceutil/trace.go:171","msg":"trace[148435517] 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:624; }","duration":"112.832157ms","start":"2026-04-15T12:17:53.442039Z","end":"2026-04-15T12:17:53.554871Z","steps":["trace[148435517] 'agreement among raft nodes before linearized reading' (duration: 112.165669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.554966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.627621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.554993Z","caller":"traceutil/trace.go:171","msg":"trace[1306371385] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:624; }","duration":"161.671552ms","start":"2026-04-15T12:17:53.393315Z","end":"2026-04-15T12:17:53.554986Z","steps":["trace[1306371385] 'agreement among raft nodes before linearized reading' (duration: 161.631991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.555112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.163179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.55516Z","caller":"traceutil/trace.go:171","msg":"trace[72020730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:624; }","duration":"161.294222ms","start":"2026-04-15T12:17:53.393855Z","end":"2026-04-15T12:17:53.555149Z","steps":["trace[72020730] 'agreement among raft nodes before linearized reading' (duration: 161.21992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.555307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.15386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:53.555304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.379854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:53.555307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.058932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.555337Z","caller":"traceutil/trace.go:171","msg":"trace[321962828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"189.218732ms","start":"2026-04-15T12:17:53.366111Z","end":"2026-04-15T12:17:53.55533Z","steps":["trace[321962828] 'agreement among raft nodes before linearized reading' (duration: 189.17026ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:53.555343Z","caller":"traceutil/trace.go:171","msg":"trace[218287786] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"138.488047ms","start":"2026-04-15T12:17:53.416846Z","end":"2026-04-15T12:17:53.555334Z","steps":["trace[218287786] 'agreement among raft nodes before linearized reading' (duration: 138.425795ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:53.555376Z","caller":"traceutil/trace.go:171","msg":"trace[1604416024] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:624; }","duration":"162.145854ms","start":"2026-04-15T12:17:53.393195Z","end":"2026-04-15T12:17:53.555341Z","steps":["trace[1604416024] 'agreement among raft nodes before linearized reading' (duration: 162.078572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.555554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.260968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:53.555637Z","caller":"traceutil/trace.go:171","msg":"trace[1388085636] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"190.360931ms","start":"2026-04-15T12:17:53.365262Z","end":"2026-04-15T12:17:53.555623Z","steps":["trace[1388085636] 'agreement among raft nodes before linearized reading' (duration: 188.679197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:53.750627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.862817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dkmx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-15T12:17:53.751008Z","caller":"traceutil/trace.go:171","msg":"trace[1118296126] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dkmx; range_end:; response_count:1; response_revision:624; }","duration":"103.295118ms","start":"2026-04-15T12:17:53.647696Z","end":"2026-04-15T12:17:53.750991Z","steps":["trace[1118296126] 'range keys from in-memory index tree' (duration: 102.721843ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:54.659894Z","caller":"traceutil/trace.go:171","msg":"trace[1192066721] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"903.895563ms","start":"2026-04-15T12:17:53.755978Z","end":"2026-04-15T12:17:54.659873Z","steps":["trace[1192066721] 'process raft request' (duration: 903.535573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:54.6601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.755966Z","time spent":"904.013316ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:17:54.659879Z","caller":"traceutil/trace.go:171","msg":"trace[287610035] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"890.712269ms","start":"2026-04-15T12:17:53.769082Z","end":"2026-04-15T12:17:54.659795Z","steps":["trace[287610035] 'read index received' (duration: 48.766734ms)","trace[287610035] 'applied index is now lower than readState.Index' (duration: 841.944435ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:54.660339Z","caller":"traceutil/trace.go:171","msg":"trace[1363291048] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"898.894382ms","start":"2026-04-15T12:17:53.761428Z","end":"2026-04-15T12:17:54.660322Z","steps":["trace[1363291048] 'process raft request' (duration: 898.298427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:54.660511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.761421Z","time spent":"899.037106ms","remote":"127.0.0.1:51782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:54.660107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.015616ms","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-04-15T12:17:54.66068Z","caller":"traceutil/trace.go:171","msg":"trace[879889028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"891.548251ms","start":"2026-04-15T12:17:53.769027Z","end":"2026-04-15T12:17:54.660575Z","steps":["trace[879889028] 'agreement among raft nodes before linearized reading' (duration: 890.906704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:54.660836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.769015Z","time spent":"891.730755ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T12:17:55.161444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603225153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T12:17:55.661591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603225153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T12:17:55.747138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.085989118s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T12:17:55.748065Z","caller":"traceutil/trace.go:171","msg":"trace[187840796] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.783806861s","start":"2026-04-15T12:17:53.96424Z","end":"2026-04-15T12:17:55.748047Z","steps":["trace[187840796] 'process raft request' (duration: 1.783366828s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:55.748187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.964217Z","time spent":"1.783907193s","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:56.285329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.585725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:17:56.285494Z","caller":"traceutil/trace.go:171","msg":"trace[2055746250] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"479.85628ms","start":"2026-04-15T12:17:55.805618Z","end":"2026-04-15T12:17:56.285475Z","steps":["trace[2055746250] 'process raft request' (duration: 479.801209ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:56.285547Z","caller":"traceutil/trace.go:171","msg":"trace[2116840347] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"1.625187277s","start":"2026-04-15T12:17:54.660333Z","end":"2026-04-15T12:17:56.285521Z","steps":["trace[2116840347] 'read index received' (duration: 1.087109307s)","trace[2116840347] 'applied index is now lower than readState.Index' (duration: 538.07594ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:17:56.285674Z","caller":"traceutil/trace.go:171","msg":"trace[1468488530] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.620630029s","start":"2026-04-15T12:17:54.665032Z","end":"2026-04-15T12:17:56.285662Z","steps":["trace[1468488530] 'process raft request' (duration: 1.200637862s)","trace[1468488530] 'compare' (duration: 419.462222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:56.285781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.665009Z","time spent":"1.620697581s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:17:56.285869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.227646911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.285923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2747737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.285979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20235149s","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:5"} {"level":"warn","ts":"2026-04-15T12:17:56.285994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.39614445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286014Z","caller":"traceutil/trace.go:171","msg":"trace[1166097784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"2.202387181s","start":"2026-04-15T12:17:54.083619Z","end":"2026-04-15T12:17:56.286006Z","steps":["trace[1166097784] 'agreement among raft nodes before linearized reading' (duration: 2.202339639s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248419792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286032Z","caller":"traceutil/trace.go:171","msg":"trace[1845385134] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"2.396202611s","start":"2026-04-15T12:17:53.889817Z","end":"2026-04-15T12:17:56.28602Z","steps":["trace[1845385134] 'agreement among raft nodes before linearized reading' (duration: 2.396143939s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.28607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.889803Z","time spent":"2.396257493s","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T12:17:56.286079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.083594Z","time spent":"2.202466972s","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:17:56.286134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.175214521s","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-04-15T12:17:56.286204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.275200901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.286253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248947806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286261Z","caller":"traceutil/trace.go:171","msg":"trace[620906230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"2.175350315s","start":"2026-04-15T12:17:54.110895Z","end":"2026-04-15T12:17:56.286245Z","steps":["trace[620906230] 'agreement among raft nodes before linearized reading' (duration: 2.17515088s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184274497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.286334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.202885403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286355Z","caller":"traceutil/trace.go:171","msg":"trace[13463339] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"2.184319108s","start":"2026-04-15T12:17:54.102025Z","end":"2026-04-15T12:17:56.286345Z","steps":["trace[13463339] 'agreement among raft nodes before linearized reading' (duration: 2.184259467s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:56.286371Z","caller":"traceutil/trace.go:171","msg":"trace[276024802] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"2.202944955s","start":"2026-04-15T12:17:54.083414Z","end":"2026-04-15T12:17:56.286359Z","steps":["trace[276024802] 'agreement among raft nodes before linearized reading' (duration: 2.202891983s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.27475141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.286397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.101993Z","time spent":"2.18439329s","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-15T12:17:56.286404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.083401Z","time spent":"2.202994376s","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-15T12:17:56.286359Z","caller":"traceutil/trace.go:171","msg":"trace[1387310677] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"2.275301743s","start":"2026-04-15T12:17:54.010962Z","end":"2026-04-15T12:17:56.286264Z","steps":["trace[1387310677] 'agreement among raft nodes before linearized reading' (duration: 2.275199791s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:56.286414Z","caller":"traceutil/trace.go:171","msg":"trace[1363230550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"2.274812881s","start":"2026-04-15T12:17:54.011588Z","end":"2026-04-15T12:17:56.286401Z","steps":["trace[1363230550] 'agreement among raft nodes before linearized reading' (duration: 2.274752089s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184865512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.286448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.011581Z","time spent":"2.274858862s","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-15T12:17:56.286012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.396039097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286484Z","caller":"traceutil/trace.go:171","msg":"trace[389227563] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"2.184952805s","start":"2026-04-15T12:17:54.101517Z","end":"2026-04-15T12:17:56.28647Z","steps":["trace[389227563] 'agreement among raft nodes before linearized reading' (duration: 2.184883173s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22765749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286567Z","caller":"traceutil/trace.go:171","msg":"trace[2074906210] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:630; }","duration":"2.248993317s","start":"2026-04-15T12:17:54.037297Z","end":"2026-04-15T12:17:56.28629Z","steps":["trace[2074906210] 'agreement among raft nodes before linearized reading' (duration: 2.248921795s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:56.286572Z","caller":"traceutil/trace.go:171","msg":"trace[1168583053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"2.396586492s","start":"2026-04-15T12:17:53.889921Z","end":"2026-04-15T12:17:56.286508Z","steps":["trace[1168583053] 'agreement among raft nodes before linearized reading' (duration: 2.395990866s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.037274Z","time spent":"2.249328516s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T12:17:56.286519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.101499Z","time spent":"2.185011156s","remote":"127.0.0.1:54586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-15T12:17:56.286629Z","caller":"traceutil/trace.go:171","msg":"trace[690015311] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:630; }","duration":"2.227713811s","start":"2026-04-15T12:17:54.058805Z","end":"2026-04-15T12:17:56.286519Z","steps":["trace[690015311] 'agreement among raft nodes before linearized reading' (duration: 2.22767107s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:53.889918Z","time spent":"2.396710945s","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:17:56.286717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.058797Z","time spent":"2.227884285s","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-15T12:17:56.28647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.010946Z","time spent":"2.275499019s","remote":"127.0.0.1:54542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T12:17:56.286025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982446657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:17:56.286061Z","caller":"traceutil/trace.go:171","msg":"trace[1795442641] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:630; }","duration":"2.248468274s","start":"2026-04-15T12:17:54.037583Z","end":"2026-04-15T12:17:56.286051Z","steps":["trace[1795442641] 'agreement among raft nodes before linearized reading' (duration: 2.248417692s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.228542303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.286896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.037579Z","time spent":"2.249301995s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-04-15T12:17:56.286923Z","caller":"traceutil/trace.go:171","msg":"trace[617046503] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:630; }","duration":"2.228626026s","start":"2026-04-15T12:17:54.058287Z","end":"2026-04-15T12:17:56.286913Z","steps":["trace[617046503] 'agreement among raft nodes before linearized reading' (duration: 2.228577395s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.286316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.110884Z","time spent":"2.175421526s","remote":"127.0.0.1:51888","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-04-15T12:17:56.286953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.058266Z","time spent":"2.228679197s","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-15T12:17:56.285938Z","caller":"traceutil/trace.go:171","msg":"trace[1426388513] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:630; }","duration":"2.227734123s","start":"2026-04-15T12:17:54.058193Z","end":"2026-04-15T12:17:56.285927Z","steps":["trace[1426388513] 'agreement among raft nodes before linearized reading' (duration: 2.22762047s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.28699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.058181Z","time spent":"2.22879956s","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:17:56.286188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248821083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:56.285573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:55.805584Z","time spent":"479.960262ms","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:17:56.285976Z","caller":"traceutil/trace.go:171","msg":"trace[1739236636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"2.274860972s","start":"2026-04-15T12:17:54.0111Z","end":"2026-04-15T12:17:56.285961Z","steps":["trace[1739236636] 'agreement among raft nodes before linearized reading' (duration: 2.274732109s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:56.287069Z","caller":"traceutil/trace.go:171","msg":"trace[1896714163] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:630; }","duration":"2.249715016s","start":"2026-04-15T12:17:54.037342Z","end":"2026-04-15T12:17:56.287057Z","steps":["trace[1896714163] 'agreement among raft nodes before linearized reading' (duration: 2.248753421s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.2871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.01107Z","time spent":"2.276019522s","remote":"127.0.0.1:54542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:17:56.287123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.037339Z","time spent":"2.249771877s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-15T12:17:56.287189Z","caller":"traceutil/trace.go:171","msg":"trace[674389232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.983303359s","start":"2026-04-15T12:17:54.303543Z","end":"2026-04-15T12:17:56.286847Z","steps":["trace[674389232] 'agreement among raft nodes before linearized reading' (duration: 1.982458868s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.287259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.303529Z","time spent":"1.98371624s","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T12:17:56.285958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184491873s","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:5"} {"level":"info","ts":"2026-04-15T12:17:56.287317Z","caller":"traceutil/trace.go:171","msg":"trace[1355934006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"2.185893589s","start":"2026-04-15T12:17:54.101413Z","end":"2026-04-15T12:17:56.287306Z","steps":["trace[1355934006] 'agreement among raft nodes before linearized reading' (duration: 2.184453442s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:56.287352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:54.101405Z","time spent":"2.1859358s","remote":"127.0.0.1:54586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:17:56.786129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603225157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T12:17:57.286401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603225157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T12:17:57.787154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603225157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T12:17:58.058552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.915509318s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-15T12:17:58.294511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000135809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-15T12:17:58.294588Z","caller":"traceutil/trace.go:171","msg":"trace[1600212467] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000241472s","start":"2026-04-15T12:17:56.294329Z","end":"2026-04-15T12:17:58.294571Z","steps":["trace[1600212467] 'agreement among raft nodes before linearized reading' (duration: 2.000145869s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.29465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:56.294322Z","time spent":"2.000318883s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/15 12:17:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-15T12:17:58.494858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.004776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:17:58.495083Z","caller":"traceutil/trace.go:171","msg":"trace[2099491537] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.505893905s","start":"2026-04-15T12:17:55.989165Z","end":"2026-04-15T12:17:58.495059Z","steps":["trace[2099491537] 'process raft request' (duration: 2.069578241s)","trace[2099491537] 'compare' (duration: 435.866713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:58.495186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:55.989139Z","time spent":"2.506001297s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:17:58.495532Z","caller":"traceutil/trace.go:171","msg":"trace[1191088263] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.202849612s","start":"2026-04-15T12:17:56.292656Z","end":"2026-04-15T12:17:58.495506Z","steps":["trace[1191088263] 'process raft request' (duration: 2.202317228s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.495658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:56.292638Z","time spent":"2.202954975s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:17:58.495274Z","caller":"traceutil/trace.go:171","msg":"trace[1579424881] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"2.209608879s","start":"2026-04-15T12:17:56.285611Z","end":"2026-04-15T12:17:58.49522Z","steps":["trace[1579424881] 'read index received' (duration: 1.773116351s)","trace[1579424881] 'applied index is now lower than readState.Index' (duration: 436.491308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:17:58.496098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.6665335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-15T12:17:58.496171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.82035ms","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-04-15T12:17:58.49618Z","caller":"traceutil/trace.go:171","msg":"trace[113724594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.666656264s","start":"2026-04-15T12:17:55.829503Z","end":"2026-04-15T12:17:58.496159Z","steps":["trace[113724594] 'agreement among raft nodes before linearized reading' (duration: 2.666450438s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:58.496212Z","caller":"traceutil/trace.go:171","msg":"trace[986068982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"204.862281ms","start":"2026-04-15T12:17:58.291339Z","end":"2026-04-15T12:17:58.496202Z","steps":["trace[986068982] 'agreement among raft nodes before linearized reading' (duration: 204.758738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.496229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:55.829483Z","time spent":"2.666733245s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T12:17:58.49635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.235695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:17:58.496117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204418083s","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-04-15T12:17:58.496379Z","caller":"traceutil/trace.go:171","msg":"trace[356766245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"201.290486ms","start":"2026-04-15T12:17:58.295081Z","end":"2026-04-15T12:17:58.496371Z","steps":["trace[356766245] 'agreement among raft nodes before linearized reading' (duration: 201.249375ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:17:58.496413Z","caller":"traceutil/trace.go:171","msg":"trace[1667464476] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:632; }","duration":"2.204768583s","start":"2026-04-15T12:17:56.291631Z","end":"2026-04-15T12:17:58.496399Z","steps":["trace[1667464476] 'agreement among raft nodes before linearized reading' (duration: 2.204428054s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.49646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:56.291621Z","time spent":"2.204828374s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-15T12:17:58.496758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830263894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:17:58.496838Z","caller":"traceutil/trace.go:171","msg":"trace[483013119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.830374636s","start":"2026-04-15T12:17:56.666435Z","end":"2026-04-15T12:17:58.496809Z","steps":["trace[483013119] 'agreement among raft nodes before linearized reading' (duration: 1.830153971s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.496799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.014661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-15T12:17:58.496952Z","caller":"traceutil/trace.go:171","msg":"trace[1823584930] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:632; }","duration":"705.184095ms","start":"2026-04-15T12:17:57.791754Z","end":"2026-04-15T12:17:58.496938Z","steps":["trace[1823584930] 'agreement among raft nodes before linearized reading' (duration: 704.391224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:17:58.497016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:57.79174Z","time spent":"705.263246ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-04-15T12:17:58.496896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:17:56.666415Z","time spent":"1.830467918s","remote":"127.0.0.1:51888","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-04-15T12:18:01.807795Z","caller":"traceutil/trace.go:171","msg":"trace[783521711] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"112.838837ms","start":"2026-04-15T12:18:01.694936Z","end":"2026-04-15T12:18:01.807775Z","steps":["trace[783521711] 'process raft request' (duration: 112.680433ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.749395Z","caller":"traceutil/trace.go:171","msg":"trace[1355343774] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"120.36973ms","start":"2026-04-15T12:18:02.629008Z","end":"2026-04-15T12:18:02.749377Z","steps":["trace[1355343774] 'read index received' (duration: 25.034925ms)","trace[1355343774] 'applied index is now lower than readState.Index' (duration: 95.333905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:02.749635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.602766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:02.749613Z","caller":"traceutil/trace.go:171","msg":"trace[1686756236] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"120.719269ms","start":"2026-04-15T12:18:02.628857Z","end":"2026-04-15T12:18:02.749576Z","steps":["trace[1686756236] 'process raft request' (duration: 25.22189ms)","trace[1686756236] 'compare' (duration: 95.178251ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:02.749747Z","caller":"traceutil/trace.go:171","msg":"trace[951575150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:669; }","duration":"120.715239ms","start":"2026-04-15T12:18:02.628989Z","end":"2026-04-15T12:18:02.749705Z","steps":["trace[951575150] 'agreement among raft nodes before linearized reading' (duration: 120.577066ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.78213Z","caller":"traceutil/trace.go:171","msg":"trace[1828784601] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"149.48796ms","start":"2026-04-15T12:18:02.632627Z","end":"2026-04-15T12:18:02.782115Z","steps":["trace[1828784601] 'process raft request' (duration: 149.45586ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.782284Z","caller":"traceutil/trace.go:171","msg":"trace[872672891] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"150.418964ms","start":"2026-04-15T12:18:02.63183Z","end":"2026-04-15T12:18:02.782249Z","steps":["trace[872672891] 'process raft request' (duration: 150.171737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:02.834349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.260586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:18:02.834375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.932173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:02.834447Z","caller":"traceutil/trace.go:171","msg":"trace[378745975] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"139.593185ms","start":"2026-04-15T12:18:02.694839Z","end":"2026-04-15T12:18:02.834432Z","steps":["trace[378745975] 'process raft request' (duration: 139.187635ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.834451Z","caller":"traceutil/trace.go:171","msg":"trace[1035588282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:672; }","duration":"205.384249ms","start":"2026-04-15T12:18:02.629039Z","end":"2026-04-15T12:18:02.834423Z","steps":["trace[1035588282] 'agreement among raft nodes before linearized reading' (duration: 205.220465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:02.834507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.094277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:02.834654Z","caller":"traceutil/trace.go:171","msg":"trace[724252474] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:672; }","duration":"204.269131ms","start":"2026-04-15T12:18:02.630354Z","end":"2026-04-15T12:18:02.834623Z","steps":["trace[724252474] 'agreement among raft nodes before linearized reading' (duration: 204.081596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:02.834379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.038148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dkmx\" ","response":"range_response_count:1 size:17970"} {"level":"info","ts":"2026-04-15T12:18:02.834674Z","caller":"traceutil/trace.go:171","msg":"trace[1781218471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:672; }","duration":"204.084806ms","start":"2026-04-15T12:18:02.630383Z","end":"2026-04-15T12:18:02.834468Z","steps":["trace[1781218471] 'agreement among raft nodes before linearized reading' (duration: 203.83505ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.834727Z","caller":"traceutil/trace.go:171","msg":"trace[444585783] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dkmx; range_end:; response_count:1; response_revision:672; }","duration":"142.433459ms","start":"2026-04-15T12:18:02.692285Z","end":"2026-04-15T12:18:02.834718Z","steps":["trace[444585783] 'agreement among raft nodes before linearized reading' (duration: 141.946586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:02.834365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.312667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:18:02.834957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.668819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:02.835063Z","caller":"traceutil/trace.go:171","msg":"trace[875160832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:672; }","duration":"202.800163ms","start":"2026-04-15T12:18:02.632221Z","end":"2026-04-15T12:18:02.835021Z","steps":["trace[875160832] 'agreement among raft nodes before linearized reading' (duration: 201.981082ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.834903Z","caller":"traceutil/trace.go:171","msg":"trace[694004952] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:672; }","duration":"205.841701ms","start":"2026-04-15T12:18:02.629021Z","end":"2026-04-15T12:18:02.834863Z","steps":["trace[694004952] 'agreement among raft nodes before linearized reading' (duration: 205.263507ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:02.917182Z","caller":"traceutil/trace.go:171","msg":"trace[597863999] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"131.485396ms","start":"2026-04-15T12:18:02.785668Z","end":"2026-04-15T12:18:02.917153Z","steps":["trace[597863999] 'process raft request' (duration: 110.350891ms)","trace[597863999] 'compare' (duration: 20.770096ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:02.917459Z","caller":"traceutil/trace.go:171","msg":"trace[813626915] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"130.574543ms","start":"2026-04-15T12:18:02.786828Z","end":"2026-04-15T12:18:02.917402Z","steps":["trace[813626915] 'process raft request' (duration: 130.350648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:03.174986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.917126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:18:03.175094Z","caller":"traceutil/trace.go:171","msg":"trace[956866060] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"327.927676ms","start":"2026-04-15T12:18:02.847147Z","end":"2026-04-15T12:18:03.175074Z","steps":["trace[956866060] 'process raft request' (duration: 197.849595ms)","trace[956866060] 'compare' (duration: 129.727362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:03.175161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:02.847129Z","time spent":"327.996477ms","remote":"127.0.0.1:51782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:18:03.281854Z","caller":"traceutil/trace.go:171","msg":"trace[1622592564] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"253.9313ms","start":"2026-04-15T12:18:03.027901Z","end":"2026-04-15T12:18:03.281833Z","steps":["trace[1622592564] 'read index received' (duration: 17.10767ms)","trace[1622592564] 'applied index is now lower than readState.Index' (duration: 236.81829ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:03.282099Z","caller":"traceutil/trace.go:171","msg":"trace[1580915220] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"360.026043ms","start":"2026-04-15T12:18:02.922062Z","end":"2026-04-15T12:18:03.282088Z","steps":["trace[1580915220] 'process raft request' (duration: 359.721855ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:03.282152Z","caller":"traceutil/trace.go:171","msg":"trace[131344955] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"360.208748ms","start":"2026-04-15T12:18:02.921809Z","end":"2026-04-15T12:18:03.282017Z","steps":["trace[131344955] 'process raft request' (duration: 354.403488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:03.282208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:02.922047Z","time spent":"360.086605ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:18:03.282318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:02.921776Z","time spent":"360.439753ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:18:03.282507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.607197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:03.282549Z","caller":"traceutil/trace.go:171","msg":"trace[1680495106] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"254.688149ms","start":"2026-04-15T12:18:03.02785Z","end":"2026-04-15T12:18:03.282538Z","steps":["trace[1680495106] 'agreement among raft nodes before linearized reading' (duration: 254.564676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:03.282816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.381132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-llm6p\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-04-15T12:18:03.283034Z","caller":"traceutil/trace.go:171","msg":"trace[958029507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-llm6p; range_end:; response_count:1; response_revision:677; }","duration":"103.499556ms","start":"2026-04-15T12:18:03.179376Z","end":"2026-04-15T12:18:03.282875Z","steps":["trace[958029507] 'agreement among raft nodes before linearized reading' (duration: 103.090725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:03.540043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:18:03.540156Z","caller":"traceutil/trace.go:171","msg":"trace[658340933] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"200.992357ms","start":"2026-04-15T12:18:03.339144Z","end":"2026-04-15T12:18:03.540137Z","steps":["trace[658340933] 'process raft request' (duration: 75.72807ms)","trace[658340933] 'compare' (duration: 124.60619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:03.83404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.551574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:18:03.834195Z","caller":"traceutil/trace.go:171","msg":"trace[1176438369] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"459.913195ms","start":"2026-04-15T12:18:03.374257Z","end":"2026-04-15T12:18:03.83417Z","steps":["trace[1176438369] 'process raft request' (duration: 280.125915ms)","trace[1176438369] 'compare' (duration: 179.40125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:03.83427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:03.374237Z","time spent":"459.999197ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:18:03.852298Z","caller":"traceutil/trace.go:171","msg":"trace[1213527664] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"222.905471ms","start":"2026-04-15T12:18:03.629377Z","end":"2026-04-15T12:18:03.852283Z","steps":["trace[1213527664] 'process raft request' (duration: 222.86233ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:03.852422Z","caller":"traceutil/trace.go:171","msg":"trace[1941336157] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"421.610818ms","start":"2026-04-15T12:18:03.430802Z","end":"2026-04-15T12:18:03.852413Z","steps":["trace[1941336157] 'process raft request' (duration: 421.32166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:03.852521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:03.430777Z","time spent":"421.708081ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T12:18:03.852654Z","caller":"traceutil/trace.go:171","msg":"trace[318362895] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"267.223593ms","start":"2026-04-15T12:18:03.585407Z","end":"2026-04-15T12:18:03.852631Z","steps":["trace[318362895] 'process raft request' (duration: 266.786272ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:03.852746Z","caller":"traceutil/trace.go:171","msg":"trace[903884690] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:710; }","duration":"147.92063ms","start":"2026-04-15T12:18:03.704772Z","end":"2026-04-15T12:18:03.852692Z","steps":["trace[903884690] 'read index received' (duration: 147.916839ms)","trace[903884690] 'applied index is now lower than readState.Index' (duration: 2.881µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:03.852906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.135896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dkmx\" ","response":"range_response_count:1 size:18063"} {"level":"info","ts":"2026-04-15T12:18:03.852935Z","caller":"traceutil/trace.go:171","msg":"trace[1855012904] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dkmx; range_end:; response_count:1; response_revision:685; }","duration":"148.197606ms","start":"2026-04-15T12:18:03.704727Z","end":"2026-04-15T12:18:03.852924Z","steps":["trace[1855012904] 'agreement among raft nodes before linearized reading' (duration: 148.048243ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:04.091854Z","caller":"traceutil/trace.go:171","msg":"trace[913579853] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"143.834004ms","start":"2026-04-15T12:18:03.947997Z","end":"2026-04-15T12:18:04.091831Z","steps":["trace[913579853] 'process raft request' (duration: 143.700091ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:04.120177Z","caller":"traceutil/trace.go:171","msg":"trace[4927002] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"165.889762ms","start":"2026-04-15T12:18:03.954252Z","end":"2026-04-15T12:18:04.120142Z","steps":["trace[4927002] 'process raft request' (duration: 165.600405ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:05.462266Z","caller":"traceutil/trace.go:171","msg":"trace[811441624] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"157.866165ms","start":"2026-04-15T12:18:05.304375Z","end":"2026-04-15T12:18:05.462241Z","steps":["trace[811441624] 'read index received' (duration: 157.624279ms)","trace[811441624] 'applied index is now lower than readState.Index' (duration: 240.006µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:05.46244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.041399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:05.462479Z","caller":"traceutil/trace.go:171","msg":"trace[930044216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"158.127072ms","start":"2026-04-15T12:18:05.304342Z","end":"2026-04-15T12:18:05.462469Z","steps":["trace[930044216] 'agreement among raft nodes before linearized reading' (duration: 157.997339ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:05.462703Z","caller":"traceutil/trace.go:171","msg":"trace[1637709607] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"204.288811ms","start":"2026-04-15T12:18:05.258348Z","end":"2026-04-15T12:18:05.462637Z","steps":["trace[1637709607] 'process raft request' (duration: 203.726367ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:05.776004Z","caller":"traceutil/trace.go:171","msg":"trace[533238543] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"117.60984ms","start":"2026-04-15T12:18:05.658368Z","end":"2026-04-15T12:18:05.775978Z","steps":["trace[533238543] 'process raft request' (duration: 116.946633ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:05.861005Z","caller":"traceutil/trace.go:171","msg":"trace[96694261] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"133.52718ms","start":"2026-04-15T12:18:05.727459Z","end":"2026-04-15T12:18:05.860986Z","steps":["trace[96694261] 'read index received' (duration: 47.924585ms)","trace[96694261] 'applied index is now lower than readState.Index' (duration: 85.601665ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:05.861055Z","caller":"traceutil/trace.go:171","msg":"trace[526317191] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"132.550274ms","start":"2026-04-15T12:18:05.728499Z","end":"2026-04-15T12:18:05.861049Z","steps":["trace[526317191] 'process raft request' (duration: 132.443061ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:05.861019Z","caller":"traceutil/trace.go:171","msg":"trace[1929409423] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"197.938008ms","start":"2026-04-15T12:18:05.663067Z","end":"2026-04-15T12:18:05.861005Z","steps":["trace[1929409423] 'process raft request' (duration: 197.693892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:05.861264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.790516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dkmx\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-04-15T12:18:05.861402Z","caller":"traceutil/trace.go:171","msg":"trace[314132592] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dkmx; range_end:; response_count:1; response_revision:721; }","duration":"133.973861ms","start":"2026-04-15T12:18:05.727415Z","end":"2026-04-15T12:18:05.861389Z","steps":["trace[314132592] 'agreement among raft nodes before linearized reading' (duration: 133.671613ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:14.799394Z","caller":"traceutil/trace.go:171","msg":"trace[890971415] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"134.240918ms","start":"2026-04-15T12:18:14.665116Z","end":"2026-04-15T12:18:14.799357Z","steps":["trace[890971415] 'process raft request' (duration: 133.9217ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:16.927894Z","caller":"traceutil/trace.go:171","msg":"trace[104051794] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"124.074596ms","start":"2026-04-15T12:18:16.8038Z","end":"2026-04-15T12:18:16.927875Z","steps":["trace[104051794] 'read index received' (duration: 123.948992ms)","trace[104051794] 'applied index is now lower than readState.Index' (duration: 124.864µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:16.928031Z","caller":"traceutil/trace.go:171","msg":"trace[1896027168] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"140.892628ms","start":"2026-04-15T12:18:16.787121Z","end":"2026-04-15T12:18:16.928014Z","steps":["trace[1896027168] 'process raft request' (duration: 140.643672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:16.928128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.068837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:18:16.928155Z","caller":"traceutil/trace.go:171","msg":"trace[1869087584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"107.110539ms","start":"2026-04-15T12:18:16.821037Z","end":"2026-04-15T12:18:16.928147Z","steps":["trace[1869087584] 'agreement among raft nodes before linearized reading' (duration: 107.052467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:16.928133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.335882ms","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-04-15T12:18:16.928206Z","caller":"traceutil/trace.go:171","msg":"trace[1268426857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"124.433715ms","start":"2026-04-15T12:18:16.803765Z","end":"2026-04-15T12:18:16.928199Z","steps":["trace[1268426857] 'agreement among raft nodes before linearized reading' (duration: 124.194469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:16.928066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.545691ms","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-04-15T12:18:16.928415Z","caller":"traceutil/trace.go:171","msg":"trace[830255093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"116.93338ms","start":"2026-04-15T12:18:16.811468Z","end":"2026-04-15T12:18:16.928402Z","steps":["trace[830255093] 'agreement among raft nodes before linearized reading' (duration: 116.502849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:16.928492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.976442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:18:16.928555Z","caller":"traceutil/trace.go:171","msg":"trace[893114291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"117.030373ms","start":"2026-04-15T12:18:16.811483Z","end":"2026-04-15T12:18:16.928513Z","steps":["trace[893114291] 'agreement among raft nodes before linearized reading' (duration: 116.884869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:16.928761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.969342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:18:16.928794Z","caller":"traceutil/trace.go:171","msg":"trace[1172180723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"117.002043ms","start":"2026-04-15T12:18:16.811782Z","end":"2026-04-15T12:18:16.928784Z","steps":["trace[1172180723] 'agreement among raft nodes before linearized reading' (duration: 116.89271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:18.485802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:17.794087Z","time spent":"691.711405ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-15T12:18:18.492811Z","caller":"traceutil/trace.go:171","msg":"trace[2081261107] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"188.097745ms","start":"2026-04-15T12:18:18.304695Z","end":"2026-04-15T12:18:18.492792Z","steps":["trace[2081261107] 'read index received' (duration: 181.109534ms)","trace[2081261107] 'applied index is now lower than readState.Index' (duration: 6.987501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:18:18.492923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.211607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:18.492947Z","caller":"traceutil/trace.go:171","msg":"trace[786122396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"188.281829ms","start":"2026-04-15T12:18:18.304657Z","end":"2026-04-15T12:18:18.492939Z","steps":["trace[786122396] 'agreement among raft nodes before linearized reading' (duration: 188.207527ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:18:18.493286Z","caller":"traceutil/trace.go:171","msg":"trace[1639984909] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"556.899283ms","start":"2026-04-15T12:18:17.936368Z","end":"2026-04-15T12:18:18.493267Z","steps":["trace[1639984909] 'process raft request' (duration: 556.314138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:18.493426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:17.936344Z","time spent":"557.006286ms","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:18:32.442762Z","caller":"traceutil/trace.go:171","msg":"trace[1550445680] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"217.284895ms","start":"2026-04-15T12:18:32.225449Z","end":"2026-04-15T12:18:32.442733Z","steps":["trace[1550445680] 'process raft request' (duration: 217.126821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.570563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322068939s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-15T12:18:54.570787Z","caller":"traceutil/trace.go:171","msg":"trace[538691235] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1057; }","duration":"1.299862127s","start":"2026-04-15T12:18:53.270908Z","end":"2026-04-15T12:18:54.570771Z","steps":["trace[538691235] 'read index received' (duration: 426.70484ms)","trace[538691235] 'applied index is now lower than readState.Index' (duration: 873.156537ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:18:54.570987Z","caller":"traceutil/trace.go:171","msg":"trace[1762942627] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.456768308s","start":"2026-04-15T12:18:53.114207Z","end":"2026-04-15T12:18:54.570975Z","steps":["trace[1762942627] 'process raft request' (duration: 1.45645103s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.571079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.11416Z","time spent":"1.45685972s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:18:54.572345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267796802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:18:54.572486Z","caller":"traceutil/trace.go:171","msg":"trace[1483375699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.267971127s","start":"2026-04-15T12:18:53.304493Z","end":"2026-04-15T12:18:54.572464Z","steps":["trace[1483375699] 'agreement among raft nodes before linearized reading' (duration: 1.267558526s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.572521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263187033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:18:54.572572Z","caller":"traceutil/trace.go:171","msg":"trace[1395580752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.263261865s","start":"2026-04-15T12:18:53.309296Z","end":"2026-04-15T12:18:54.572558Z","steps":["trace[1395580752] 'agreement among raft nodes before linearized reading' (duration: 1.263084931s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.572594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.583172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T12:18:54.572606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.309254Z","time spent":"1.263344197s","remote":"127.0.0.1:51888","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-04-15T12:18:54.572626Z","caller":"traceutil/trace.go:171","msg":"trace[947942966] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1021; }","duration":"314.637284ms","start":"2026-04-15T12:18:54.257979Z","end":"2026-04-15T12:18:54.572616Z","steps":["trace[947942966] 'agreement among raft nodes before linearized reading' (duration: 314.577422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.572653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:54.257963Z","time spent":"314.682084ms","remote":"127.0.0.1:52066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:18:54.572686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265251426s","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-04-15T12:18:54.572731Z","caller":"traceutil/trace.go:171","msg":"trace[2132025933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"1.265291548s","start":"2026-04-15T12:18:53.307416Z","end":"2026-04-15T12:18:54.572707Z","steps":["trace[2132025933] 'agreement among raft nodes before linearized reading' (duration: 1.265230646s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.572755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.307408Z","time spent":"1.265341589s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T12:18:54.572817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268154812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:18:54.572839Z","caller":"traceutil/trace.go:171","msg":"trace[923102991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"1.268215213s","start":"2026-04-15T12:18:53.304617Z","end":"2026-04-15T12:18:54.572833Z","steps":["trace[923102991] 'agreement among raft nodes before linearized reading' (duration: 1.268181522s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.572855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.304597Z","time spent":"1.268253724s","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T12:18:54.572908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.304476Z","time spent":"1.268072619s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T12:18:54.572387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032086291s","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-04-15T12:18:54.57304Z","caller":"traceutil/trace.go:171","msg":"trace[65578369] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1021; }","duration":"1.032767929s","start":"2026-04-15T12:18:53.54025Z","end":"2026-04-15T12:18:54.573018Z","steps":["trace[65578369] 'agreement among raft nodes before linearized reading' (duration: 1.032095832s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.57307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.540224Z","time spent":"1.03283668s","remote":"127.0.0.1:52112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:18:54.572423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263249665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:18:54.573118Z","caller":"traceutil/trace.go:171","msg":"trace[98525258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.263956212s","start":"2026-04-15T12:18:53.30915Z","end":"2026-04-15T12:18:54.573106Z","steps":["trace[98525258] 'agreement among raft nodes before linearized reading' (duration: 1.263210513s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.573166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.309143Z","time spent":"1.264012844s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T12:18:54.573073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302177597s","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-04-15T12:18:54.573398Z","caller":"traceutil/trace.go:171","msg":"trace[987635233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"1.302503905s","start":"2026-04-15T12:18:53.270886Z","end":"2026-04-15T12:18:54.57339Z","steps":["trace[987635233] 'agreement among raft nodes before linearized reading' (duration: 1.30111835s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:18:54.573428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:18:53.270815Z","time spent":"1.302604238s","remote":"127.0.0.1:51888","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-04-15T12:19:17.262169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603226135,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T12:19:17.411999Z","caller":"traceutil/trace.go:171","msg":"trace[692132914] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"650.891693ms","start":"2026-04-15T12:19:16.761079Z","end":"2026-04-15T12:19:17.411971Z","steps":["trace[692132914] 'read index received' (duration: 650.604215ms)","trace[692132914] 'applied index is now lower than readState.Index' (duration: 286.378µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:17.412135Z","caller":"traceutil/trace.go:171","msg":"trace[1571012124] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"772.690667ms","start":"2026-04-15T12:19:16.639421Z","end":"2026-04-15T12:19:17.412111Z","steps":["trace[1571012124] 'process raft request' (duration: 772.342328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:17.412251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.1575ms","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-04-15T12:19:17.41234Z","caller":"traceutil/trace.go:171","msg":"trace[1197825279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"651.323464ms","start":"2026-04-15T12:19:16.761006Z","end":"2026-04-15T12:19:17.412329Z","steps":["trace[1197825279] 'agreement among raft nodes before linearized reading' (duration: 651.103538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:17.412348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.167022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:19:17.412405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.639392Z","time spent":"772.869812ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:17.412417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.330552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:19:17.412527Z","caller":"traceutil/trace.go:171","msg":"trace[1761418467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"632.474386ms","start":"2026-04-15T12:19:16.780033Z","end":"2026-04-15T12:19:17.412507Z","steps":["trace[1761418467] 'agreement among raft nodes before linearized reading' (duration: 632.178059ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:17.412463Z","caller":"traceutil/trace.go:171","msg":"trace[1038012101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"107.333546ms","start":"2026-04-15T12:19:17.305105Z","end":"2026-04-15T12:19:17.412438Z","steps":["trace[1038012101] 'agreement among raft nodes before linearized reading' (duration: 107.127351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:17.412383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.760979Z","time spent":"651.394815ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T12:19:17.412865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.077342ms","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-04-15T12:19:17.412433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.745154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-15T12:19:17.412911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.980767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:19:17.41304Z","caller":"traceutil/trace.go:171","msg":"trace[1929443911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"644.141462ms","start":"2026-04-15T12:19:16.768884Z","end":"2026-04-15T12:19:17.413025Z","steps":["trace[1929443911] 'agreement among raft nodes before linearized reading' (duration: 643.488736ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:17.412991Z","caller":"traceutil/trace.go:171","msg":"trace[1547576467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"639.3625ms","start":"2026-04-15T12:19:16.773615Z","end":"2026-04-15T12:19:17.412978Z","steps":["trace[1547576467] 'agreement among raft nodes before linearized reading' (duration: 638.630611ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:17.413007Z","caller":"traceutil/trace.go:171","msg":"trace[2131614552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"650.163304ms","start":"2026-04-15T12:19:16.762748Z","end":"2026-04-15T12:19:17.412912Z","steps":["trace[2131614552] 'agreement among raft nodes before linearized reading' (duration: 649.763984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:17.413312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.762739Z","time spent":"650.555514ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T12:19:17.412583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.780022Z","time spent":"632.550978ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T12:19:17.413138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.768875Z","time spent":"644.256385ms","remote":"127.0.0.1:51888","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-04-15T12:19:17.413303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:16.773599Z","time spent":"639.591536ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T12:19:17.608401Z","caller":"traceutil/trace.go:171","msg":"trace[74699852] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"190.195921ms","start":"2026-04-15T12:19:17.418164Z","end":"2026-04-15T12:19:17.60836Z","steps":["trace[74699852] 'process raft request' (duration: 100.901873ms)","trace[74699852] 'compare' (duration: 88.97491ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:17.608804Z","caller":"traceutil/trace.go:171","msg":"trace[1593990000] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"190.492739ms","start":"2026-04-15T12:19:17.418262Z","end":"2026-04-15T12:19:17.608754Z","steps":["trace[1593990000] 'process raft request' (duration: 190.024117ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:17.608939Z","caller":"traceutil/trace.go:171","msg":"trace[1013445702] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"190.199471ms","start":"2026-04-15T12:19:17.41872Z","end":"2026-04-15T12:19:17.60892Z","steps":["trace[1013445702] 'process raft request' (duration: 190.112089ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:17.609139Z","caller":"traceutil/trace.go:171","msg":"trace[317600628] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"190.806086ms","start":"2026-04-15T12:19:17.41832Z","end":"2026-04-15T12:19:17.609126Z","steps":["trace[317600628] 'process raft request' (duration: 190.252002ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:19.836246Z","caller":"traceutil/trace.go:171","msg":"trace[126185683] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"398.675896ms","start":"2026-04-15T12:19:19.437543Z","end":"2026-04-15T12:19:19.836219Z","steps":["trace[126185683] 'read index received' (duration: 398.479802ms)","trace[126185683] 'applied index is now lower than readState.Index' (duration: 195.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:19.836339Z","caller":"traceutil/trace.go:171","msg":"trace[1018604950] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"418.673984ms","start":"2026-04-15T12:19:19.417621Z","end":"2026-04-15T12:19:19.836295Z","steps":["trace[1018604950] 'process raft request' (duration: 418.456519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:19.836479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.915522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:19:19.83652Z","caller":"traceutil/trace.go:171","msg":"trace[436783194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"399.006095ms","start":"2026-04-15T12:19:19.437503Z","end":"2026-04-15T12:19:19.836509Z","steps":["trace[436783194] 'agreement among raft nodes before linearized reading' (duration: 398.854141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:19.836529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:19.417593Z","time spent":"418.846028ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:19.836561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:19.437482Z","time spent":"399.070646ms","remote":"127.0.0.1:51888","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-04-15T12:19:19.836801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.93865ms","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-04-15T12:19:19.836903Z","caller":"traceutil/trace.go:171","msg":"trace[1716678769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"217.076184ms","start":"2026-04-15T12:19:19.619814Z","end":"2026-04-15T12:19:19.83689Z","steps":["trace[1716678769] 'agreement among raft nodes before linearized reading' (duration: 216.913499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:19.836949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.340091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:19:19.837203Z","caller":"traceutil/trace.go:171","msg":"trace[564333793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"219.608658ms","start":"2026-04-15T12:19:19.617558Z","end":"2026-04-15T12:19:19.837167Z","steps":["trace[564333793] 'agreement among raft nodes before linearized reading' (duration: 219.318581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:19.836815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.052305ms","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-04-15T12:19:19.837314Z","caller":"traceutil/trace.go:171","msg":"trace[181947288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"223.610921ms","start":"2026-04-15T12:19:19.61369Z","end":"2026-04-15T12:19:19.837301Z","steps":["trace[181947288] 'agreement among raft nodes before linearized reading' (duration: 222.943683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:19.836801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.382546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:19:19.837418Z","caller":"traceutil/trace.go:171","msg":"trace[1828312544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"219.019832ms","start":"2026-04-15T12:19:19.61839Z","end":"2026-04-15T12:19:19.83741Z","steps":["trace[1828312544] 'agreement among raft nodes before linearized reading' (duration: 218.281053ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:22.143038Z","caller":"traceutil/trace.go:171","msg":"trace[296737340] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"220.314256ms","start":"2026-04-15T12:19:21.922693Z","end":"2026-04-15T12:19:22.143008Z","steps":["trace[296737340] 'read index received' (duration: 120.698515ms)","trace[296737340] 'applied index is now lower than readState.Index' (duration: 99.614371ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:22.143044Z","caller":"traceutil/trace.go:171","msg":"trace[1533499961] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"253.450388ms","start":"2026-04-15T12:19:21.889547Z","end":"2026-04-15T12:19:22.142998Z","steps":["trace[1533499961] 'process raft request' (duration: 153.751605ms)","trace[1533499961] 'compare' (duration: 99.453547ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:22.143143Z","caller":"traceutil/trace.go:171","msg":"trace[1277703844] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"211.751009ms","start":"2026-04-15T12:19:21.931364Z","end":"2026-04-15T12:19:22.143115Z","steps":["trace[1277703844] 'process raft request' (duration: 211.566124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.143355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.633395ms","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-04-15T12:19:22.143426Z","caller":"traceutil/trace.go:171","msg":"trace[493003717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"220.749397ms","start":"2026-04-15T12:19:21.922666Z","end":"2026-04-15T12:19:22.143415Z","steps":["trace[493003717] 'agreement among raft nodes before linearized reading' (duration: 220.46618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.143613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.42114ms","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-04-15T12:19:22.143659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.533172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:19:22.143668Z","caller":"traceutil/trace.go:171","msg":"trace[182885877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"220.506061ms","start":"2026-04-15T12:19:21.923149Z","end":"2026-04-15T12:19:22.143656Z","steps":["trace[182885877] 'agreement among raft nodes before linearized reading' (duration: 220.336997ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:22.143693Z","caller":"traceutil/trace.go:171","msg":"trace[2007015676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"220.588563ms","start":"2026-04-15T12:19:21.923094Z","end":"2026-04-15T12:19:22.143683Z","steps":["trace[2007015676] 'agreement among raft nodes before linearized reading' (duration: 220.46872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.143688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.883211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:19:22.143779Z","caller":"traceutil/trace.go:171","msg":"trace[862826237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1109; }","duration":"221.046335ms","start":"2026-04-15T12:19:21.922713Z","end":"2026-04-15T12:19:22.143759Z","steps":["trace[862826237] 'agreement among raft nodes before linearized reading' (duration: 220.781468ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:22.570613Z","caller":"traceutil/trace.go:171","msg":"trace[191346932] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"422.614195ms","start":"2026-04-15T12:19:22.147967Z","end":"2026-04-15T12:19:22.570581Z","steps":["trace[191346932] 'process raft request' (duration: 422.519042ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:22.570724Z","caller":"traceutil/trace.go:171","msg":"trace[486239005] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1153; }","duration":"266.303854ms","start":"2026-04-15T12:19:22.304411Z","end":"2026-04-15T12:19:22.570715Z","steps":["trace[486239005] 'read index received' (duration: 265.838202ms)","trace[486239005] 'applied index is now lower than readState.Index' (duration: 464.422µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:22.570791Z","caller":"traceutil/trace.go:171","msg":"trace[1241321449] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"422.535313ms","start":"2026-04-15T12:19:22.14825Z","end":"2026-04-15T12:19:22.570785Z","steps":["trace[1241321449] 'process raft request' (duration: 422.284016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.570798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:22.147961Z","time spent":"422.759358ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:22.570876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:22.148243Z","time spent":"422.564433ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:19:22.57063Z","caller":"traceutil/trace.go:171","msg":"trace[199004576] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"422.79527ms","start":"2026-04-15T12:19:22.147806Z","end":"2026-04-15T12:19:22.570601Z","steps":["trace[199004576] 'process raft request' (duration: 422.523733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.570951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:22.147786Z","time spent":"423.137308ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:22.571005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.006338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:22.571064Z","caller":"traceutil/trace.go:171","msg":"trace[58122382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1113; }","duration":"218.1105ms","start":"2026-04-15T12:19:22.352937Z","end":"2026-04-15T12:19:22.571047Z","steps":["trace[58122382] 'agreement among raft nodes before linearized reading' (duration: 218.022597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:22.571068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.645634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:22.57116Z","caller":"traceutil/trace.go:171","msg":"trace[1937997858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"266.789747ms","start":"2026-04-15T12:19:22.304347Z","end":"2026-04-15T12:19:22.571137Z","steps":["trace[1937997858] 'agreement among raft nodes before linearized reading' (duration: 266.641494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:36.632249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.219769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:36.632322Z","caller":"traceutil/trace.go:171","msg":"trace[90399883] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1204; }","duration":"247.334852ms","start":"2026-04-15T12:19:36.38497Z","end":"2026-04-15T12:19:36.632305Z","steps":["trace[90399883] 'count revisions from in-memory index tree' (duration: 247.130497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:36.632301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.371143ms","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-04-15T12:19:36.632398Z","caller":"traceutil/trace.go:171","msg":"trace[731590810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"347.510317ms","start":"2026-04-15T12:19:36.284855Z","end":"2026-04-15T12:19:36.632365Z","steps":["trace[731590810] 'range keys from in-memory index tree' (duration: 347.197839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:36.632479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:36.28483Z","time spent":"347.62831ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T12:19:36.632574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.510776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-15T12:19:36.632656Z","caller":"traceutil/trace.go:171","msg":"trace[985925589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"434.625409ms","start":"2026-04-15T12:19:36.198009Z","end":"2026-04-15T12:19:36.632634Z","steps":["trace[985925589] 'range keys from in-memory index tree' (duration: 434.301311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:36.632693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:36.197994Z","time spent":"434.690691ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T12:19:49.062726Z","caller":"traceutil/trace.go:171","msg":"trace[1056813743] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"328.037152ms","start":"2026-04-15T12:19:48.734651Z","end":"2026-04-15T12:19:49.062688Z","steps":["trace[1056813743] 'read index received' (duration: 326.280018ms)","trace[1056813743] 'applied index is now lower than readState.Index' (duration: 1.756314ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:49.062824Z","caller":"traceutil/trace.go:171","msg":"trace[1060696588] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"344.048089ms","start":"2026-04-15T12:19:48.718767Z","end":"2026-04-15T12:19:49.062815Z","steps":["trace[1060696588] 'process raft request' (duration: 343.873304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:49.062935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:48.718759Z","time spent":"344.098221ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:49.063068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.385861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:19:49.063122Z","caller":"traceutil/trace.go:171","msg":"trace[1939832380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"328.486234ms","start":"2026-04-15T12:19:48.734619Z","end":"2026-04-15T12:19:49.063105Z","steps":["trace[1939832380] 'agreement among raft nodes before linearized reading' (duration: 328.283799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:49.063168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:48.734607Z","time spent":"328.549635ms","remote":"127.0.0.1:51888","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-04-15T12:19:49.062765Z","caller":"traceutil/trace.go:171","msg":"trace[535986598] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"344.383658ms","start":"2026-04-15T12:19:48.718361Z","end":"2026-04-15T12:19:49.062745Z","steps":["trace[535986598] 'process raft request' (duration: 342.552222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:49.06322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.319203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:19:49.063327Z","caller":"traceutil/trace.go:171","msg":"trace[490648068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"327.466408ms","start":"2026-04-15T12:19:48.735837Z","end":"2026-04-15T12:19:49.063303Z","steps":["trace[490648068] 'agreement among raft nodes before linearized reading' (duration: 327.227742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:49.063369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:48.735819Z","time spent":"327.54025ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T12:19:49.063352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:48.718347Z","time spent":"344.89437ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:19:53.095747Z","caller":"traceutil/trace.go:171","msg":"trace[1282036330] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"265.039302ms","start":"2026-04-15T12:19:52.830673Z","end":"2026-04-15T12:19:53.095712Z","steps":["trace[1282036330] 'process raft request' (duration: 264.833857ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:53.78497Z","caller":"traceutil/trace.go:171","msg":"trace[186959043] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1328; }","duration":"705.952452ms","start":"2026-04-15T12:19:53.078991Z","end":"2026-04-15T12:19:53.784944Z","steps":["trace[186959043] 'read index received' (duration: 16.566941ms)","trace[186959043] 'applied index is now lower than readState.Index' (duration: 689.375631ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:53.785211Z","caller":"traceutil/trace.go:171","msg":"trace[32899009] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"830.814644ms","start":"2026-04-15T12:19:52.954356Z","end":"2026-04-15T12:19:53.785171Z","steps":["trace[32899009] 'process raft request' (duration: 830.375703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.785294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.27675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T12:19:53.785395Z","caller":"traceutil/trace.go:171","msg":"trace[1017192637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"706.429684ms","start":"2026-04-15T12:19:53.078946Z","end":"2026-04-15T12:19:53.785375Z","steps":["trace[1017192637] 'agreement among raft nodes before linearized reading' (duration: 706.167307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.785425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:52.954322Z","time spent":"830.994089ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:53.78544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:53.078923Z","time spent":"706.507805ms","remote":"127.0.0.1:51888","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-04-15T12:19:53.7851Z","caller":"traceutil/trace.go:171","msg":"trace[1236529271] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"775.130069ms","start":"2026-04-15T12:19:53.009956Z","end":"2026-04-15T12:19:53.785086Z","steps":["trace[1236529271] 'process raft request' (duration: 774.938174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.785698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:53.009924Z","time spent":"775.654182ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:19:53.897234Z","caller":"traceutil/trace.go:171","msg":"trace[2017434404] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"106.269819ms","start":"2026-04-15T12:19:53.79093Z","end":"2026-04-15T12:19:53.8972Z","steps":["trace[2017434404] 'process raft request' (duration: 106.077565ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:53.89734Z","caller":"traceutil/trace.go:171","msg":"trace[1435261683] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"112.243211ms","start":"2026-04-15T12:19:53.78505Z","end":"2026-04-15T12:19:53.897293Z","steps":["trace[1435261683] 'read index received' (duration: 14.60186ms)","trace[1435261683] 'applied index is now lower than readState.Index' (duration: 97.63452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:19:53.897766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.459819ms","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-04-15T12:19:53.897871Z","caller":"traceutil/trace.go:171","msg":"trace[2110803808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"818.593832ms","start":"2026-04-15T12:19:53.079252Z","end":"2026-04-15T12:19:53.897845Z","steps":["trace[2110803808] 'agreement among raft nodes before linearized reading' (duration: 818.348586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.897875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.391145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-15T12:19:53.897948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:53.07924Z","time spent":"818.698715ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T12:19:53.897962Z","caller":"traceutil/trace.go:171","msg":"trace[3751765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"812.511758ms","start":"2026-04-15T12:19:53.085436Z","end":"2026-04-15T12:19:53.897948Z","steps":["trace[3751765] 'agreement among raft nodes before linearized reading' (duration: 812.279172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.898014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:53.085428Z","time spent":"812.568839ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T12:19:53.898198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.177626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-15T12:19:53.898238Z","caller":"traceutil/trace.go:171","msg":"trace[936881465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"815.231217ms","start":"2026-04-15T12:19:53.082996Z","end":"2026-04-15T12:19:53.898227Z","steps":["trace[936881465] 'agreement among raft nodes before linearized reading' (duration: 815.138515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:53.89829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:53.082989Z","time spent":"815.293748ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T12:19:53.900588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.454536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:53.90065Z","caller":"traceutil/trace.go:171","msg":"trace[1237220075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1281; }","duration":"149.570438ms","start":"2026-04-15T12:19:53.751063Z","end":"2026-04-15T12:19:53.900633Z","steps":["trace[1237220075] 'agreement among raft nodes before linearized reading' (duration: 146.417108ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:54.093912Z","caller":"traceutil/trace.go:171","msg":"trace[147990193] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"190.306284ms","start":"2026-04-15T12:19:53.903538Z","end":"2026-04-15T12:19:54.093844Z","steps":["trace[147990193] 'process raft request' (duration: 190.045597ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:54.093941Z","caller":"traceutil/trace.go:171","msg":"trace[767661683] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"190.061388ms","start":"2026-04-15T12:19:53.903851Z","end":"2026-04-15T12:19:54.093912Z","steps":["trace[767661683] 'process raft request' (duration: 189.926074ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:54.094138Z","caller":"traceutil/trace.go:171","msg":"trace[107244927] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"177.433686ms","start":"2026-04-15T12:19:53.916673Z","end":"2026-04-15T12:19:54.094106Z","steps":["trace[107244927] 'read index received' (duration: 40.588991ms)","trace[107244927] 'applied index is now lower than readState.Index' (duration: 136.842555ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:54.094169Z","caller":"traceutil/trace.go:171","msg":"trace[1467916028] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"142.806178ms","start":"2026-04-15T12:19:53.95135Z","end":"2026-04-15T12:19:54.094156Z","steps":["trace[1467916028] 'process raft request' (duration: 142.47891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:54.094265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.57204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:54.09432Z","caller":"traceutil/trace.go:171","msg":"trace[691425975] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1285; }","duration":"177.663022ms","start":"2026-04-15T12:19:53.916642Z","end":"2026-04-15T12:19:54.094305Z","steps":["trace[691425975] 'agreement among raft nodes before linearized reading' (duration: 177.56159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:54.140613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.330437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:54.140739Z","caller":"traceutil/trace.go:171","msg":"trace[801738855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1286; }","duration":"144.528342ms","start":"2026-04-15T12:19:53.996184Z","end":"2026-04-15T12:19:54.140713Z","steps":["trace[801738855] 'agreement among raft nodes before linearized reading' (duration: 144.320677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:55.235387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.079666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T12:19:55.235727Z","caller":"traceutil/trace.go:171","msg":"trace[1653658070] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1341; }","duration":"136.19309ms","start":"2026-04-15T12:19:55.099493Z","end":"2026-04-15T12:19:55.235687Z","steps":["trace[1653658070] 'read index received' (duration: 135.868142ms)","trace[1653658070] 'applied index is now lower than readState.Index' (duration: 323.548µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:19:55.235934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.437036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T12:19:55.235972Z","caller":"traceutil/trace.go:171","msg":"trace[1838712223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"136.527618ms","start":"2026-04-15T12:19:55.099431Z","end":"2026-04-15T12:19:55.235959Z","steps":["trace[1838712223] 'agreement among raft nodes before linearized reading' (duration: 136.338973ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:55.236054Z","caller":"traceutil/trace.go:171","msg":"trace[1981963327] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"679.870379ms","start":"2026-04-15T12:19:54.556136Z","end":"2026-04-15T12:19:55.236006Z","steps":["trace[1981963327] 'process raft request' (duration: 508.074276ms)","trace[1981963327] 'compare' (duration: 170.747676ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:55.236084Z","caller":"traceutil/trace.go:171","msg":"trace[571340366] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"649.955819ms","start":"2026-04-15T12:19:54.586103Z","end":"2026-04-15T12:19:55.236059Z","steps":["trace[571340366] 'process raft request' (duration: 649.509958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:55.236222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:54.556123Z","time spent":"680.027574ms","remote":"127.0.0.1:51684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:19:55.236379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:54.586093Z","time spent":"650.172204ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:19:55.2366Z","caller":"traceutil/trace.go:171","msg":"trace[1565545008] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"651.022327ms","start":"2026-04-15T12:19:54.584953Z","end":"2026-04-15T12:19:55.235975Z","steps":["trace[1565545008] 'process raft request' (duration: 650.592346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:55.236958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:54.584923Z","time spent":"651.955021ms","remote":"127.0.0.1:51684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T12:19:55.866075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.584375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T12:19:55.86618Z","caller":"traceutil/trace.go:171","msg":"trace[1601280405] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1293; }","duration":"424.750519ms","start":"2026-04-15T12:19:55.441408Z","end":"2026-04-15T12:19:55.866159Z","steps":["trace[1601280405] 'count revisions from in-memory index tree' (duration: 424.488973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:55.866246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:55.441354Z","time spent":"424.870112ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-15T12:19:56.226556Z","caller":"traceutil/trace.go:171","msg":"trace[389045936] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"264.499218ms","start":"2026-04-15T12:19:55.962027Z","end":"2026-04-15T12:19:56.226526Z","steps":["trace[389045936] 'read index received' (duration: 264.330754ms)","trace[389045936] 'applied index is now lower than readState.Index' (duration: 167.634µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:56.226664Z","caller":"traceutil/trace.go:171","msg":"trace[135589861] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"319.571377ms","start":"2026-04-15T12:19:55.907056Z","end":"2026-04-15T12:19:56.226627Z","steps":["trace[135589861] 'process raft request' (duration: 319.27586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:56.226828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:55.907016Z","time spent":"319.724611ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:19:56.226976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.91396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-15T12:19:56.227112Z","caller":"traceutil/trace.go:171","msg":"trace[1499344290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"265.081134ms","start":"2026-04-15T12:19:55.962008Z","end":"2026-04-15T12:19:56.227089Z","steps":["trace[1499344290] 'agreement among raft nodes before linearized reading' (duration: 264.643602ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:56.350659Z","caller":"traceutil/trace.go:171","msg":"trace[796971249] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"204.888904ms","start":"2026-04-15T12:19:56.145742Z","end":"2026-04-15T12:19:56.350631Z","steps":["trace[796971249] 'process raft request' (duration: 204.72964ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:56.351236Z","caller":"traceutil/trace.go:171","msg":"trace[1849436796] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1347; }","duration":"124.549993ms","start":"2026-04-15T12:19:56.226648Z","end":"2026-04-15T12:19:56.351198Z","steps":["trace[1849436796] 'read index received' (duration: 124.531703ms)","trace[1849436796] 'applied index is now lower than readState.Index' (duration: 15.38µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:19:56.35129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.878838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-15T12:19:56.351389Z","caller":"traceutil/trace.go:171","msg":"trace[617324666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"252.034352ms","start":"2026-04-15T12:19:56.099345Z","end":"2026-04-15T12:19:56.351379Z","steps":["trace[617324666] 'agreement among raft nodes before linearized reading' (duration: 251.859728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:56.351515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.282202ms","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-04-15T12:19:56.351579Z","caller":"traceutil/trace.go:171","msg":"trace[603894300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"253.405846ms","start":"2026-04-15T12:19:56.09816Z","end":"2026-04-15T12:19:56.351566Z","steps":["trace[603894300] 'agreement among raft nodes before linearized reading' (duration: 253.143949ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:56.3739Z","caller":"traceutil/trace.go:171","msg":"trace[217931133] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"142.052078ms","start":"2026-04-15T12:19:56.231814Z","end":"2026-04-15T12:19:56.373866Z","steps":["trace[217931133] 'process raft request' (duration: 141.803812ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:56.517987Z","caller":"traceutil/trace.go:171","msg":"trace[1475962563] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1348; }","duration":"142.92527ms","start":"2026-04-15T12:19:56.375034Z","end":"2026-04-15T12:19:56.517959Z","steps":["trace[1475962563] 'read index received' (duration: 46.244605ms)","trace[1475962563] 'applied index is now lower than readState.Index' (duration: 96.679855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:19:56.518322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.27306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:56.518354Z","caller":"traceutil/trace.go:171","msg":"trace[1045318443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"143.358512ms","start":"2026-04-15T12:19:56.374986Z","end":"2026-04-15T12:19:56.518344Z","steps":["trace[1045318443] 'agreement among raft nodes before linearized reading' (duration: 143.26563ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:56.518813Z","caller":"traceutil/trace.go:171","msg":"trace[1969285367] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"163.187856ms","start":"2026-04-15T12:19:56.355609Z","end":"2026-04-15T12:19:56.518797Z","steps":["trace[1969285367] 'process raft request' (duration: 65.649128ms)","trace[1969285367] 'compare' (duration: 96.390708ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:56.519125Z","caller":"traceutil/trace.go:171","msg":"trace[1142921305] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"163.39083ms","start":"2026-04-15T12:19:56.355718Z","end":"2026-04-15T12:19:56.519109Z","steps":["trace[1142921305] 'process raft request' (duration: 162.155818ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:57.488947Z","caller":"traceutil/trace.go:171","msg":"trace[1515698516] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"182.219748ms","start":"2026-04-15T12:19:57.306697Z","end":"2026-04-15T12:19:57.488917Z","steps":["trace[1515698516] 'process raft request' (duration: 182.042834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:58.178133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.140616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-15T12:19:58.178219Z","caller":"traceutil/trace.go:171","msg":"trace[548632580] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1299; }","duration":"380.238208ms","start":"2026-04-15T12:19:57.797961Z","end":"2026-04-15T12:19:58.178199Z","steps":["trace[548632580] 'range keys from in-memory index tree' (duration: 379.951401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:58.178258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:19:57.797822Z","time spent":"380.427944ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"info","ts":"2026-04-15T12:19:58.520988Z","caller":"traceutil/trace.go:171","msg":"trace[1119251078] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"267.118484ms","start":"2026-04-15T12:19:58.253831Z","end":"2026-04-15T12:19:58.52095Z","steps":["trace[1119251078] 'read index received' (duration: 192.169731ms)","trace[1119251078] 'applied index is now lower than readState.Index' (duration: 74.948103ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:58.521336Z","caller":"traceutil/trace.go:171","msg":"trace[113457332] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"267.683279ms","start":"2026-04-15T12:19:58.253446Z","end":"2026-04-15T12:19:58.521129Z","steps":["trace[113457332] 'process raft request' (duration: 192.536561ms)","trace[113457332] 'compare' (duration: 74.86652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:19:58.521478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.618317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T12:19:58.522126Z","caller":"traceutil/trace.go:171","msg":"trace[154399691] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1301; }","duration":"268.309675ms","start":"2026-04-15T12:19:58.253806Z","end":"2026-04-15T12:19:58.522116Z","steps":["trace[154399691] 'agreement among raft nodes before linearized reading' (duration: 267.244527ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:58.590976Z","caller":"traceutil/trace.go:171","msg":"trace[1571263815] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"235.310587ms","start":"2026-04-15T12:19:58.355633Z","end":"2026-04-15T12:19:58.590943Z","steps":["trace[1571263815] 'process raft request' (duration: 235.02518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:58.591463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.502153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:19:58.591652Z","caller":"traceutil/trace.go:171","msg":"trace[1468391975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"287.859452ms","start":"2026-04-15T12:19:58.303774Z","end":"2026-04-15T12:19:58.591633Z","steps":["trace[1468391975] 'agreement among raft nodes before linearized reading' (duration: 287.101972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:19:58.591562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.264378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:19:58.592315Z","caller":"traceutil/trace.go:171","msg":"trace[1862684916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"214.295194ms","start":"2026-04-15T12:19:58.377998Z","end":"2026-04-15T12:19:58.592293Z","steps":["trace[1862684916] 'agreement among raft nodes before linearized reading' (duration: 212.883177ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:58.694854Z","caller":"traceutil/trace.go:171","msg":"trace[1417933953] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"100.231926ms","start":"2026-04-15T12:19:58.594585Z","end":"2026-04-15T12:19:58.694817Z","steps":["trace[1417933953] 'process raft request' (duration: 56.945366ms)","trace[1417933953] 'compare' (duration: 43.133155ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:19:58.722535Z","caller":"traceutil/trace.go:171","msg":"trace[96826670] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"127.869478ms","start":"2026-04-15T12:19:58.594638Z","end":"2026-04-15T12:19:58.722507Z","steps":["trace[96826670] 'process raft request' (duration: 127.679573ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:19:58.7228Z","caller":"traceutil/trace.go:171","msg":"trace[903109754] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"126.950534ms","start":"2026-04-15T12:19:58.595804Z","end":"2026-04-15T12:19:58.722754Z","steps":["trace[903109754] 'process raft request' (duration: 126.656607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:07.110781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954058603226721,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T12:20:07.172184Z","caller":"traceutil/trace.go:171","msg":"trace[1934450861] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"616.0814ms","start":"2026-04-15T12:20:06.556065Z","end":"2026-04-15T12:20:07.172146Z","steps":["trace[1934450861] 'process raft request' (duration: 615.859014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:07.172608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.556043Z","time spent":"616.280464ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T12:20:08.556241Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.384319514s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T12:20:08.557232Z","caller":"traceutil/trace.go:171","msg":"trace[1082120576] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1418; }","duration":"1.947462407s","start":"2026-04-15T12:20:06.609716Z","end":"2026-04-15T12:20:08.557179Z","steps":["trace[1082120576] 'read index received' (duration: 562.20169ms)","trace[1082120576] 'applied index is now lower than readState.Index' (duration: 1.385259397s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:20:08.557556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947812767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-15T12:20:08.557593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.127367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T12:20:08.557619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210502019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:20:08.55766Z","caller":"traceutil/trace.go:171","msg":"trace[652549994] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1366; }","duration":"1.947987071s","start":"2026-04-15T12:20:06.609649Z","end":"2026-04-15T12:20:08.557636Z","steps":["trace[652549994] 'agreement among raft nodes before linearized reading' (duration: 1.947692243s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.557666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833817781s","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-04-15T12:20:08.557718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.60961Z","time spent":"1.948093634s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-15T12:20:08.557836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024703709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-15T12:20:08.55785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80542011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T12:20:08.557905Z","caller":"traceutil/trace.go:171","msg":"trace[755169551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"1.024782782s","start":"2026-04-15T12:20:07.533083Z","end":"2026-04-15T12:20:08.557866Z","steps":["trace[755169551] 'agreement among raft nodes before linearized reading' (duration: 1.024677359s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:20:08.557931Z","caller":"traceutil/trace.go:171","msg":"trace[203130493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"1.805519793s","start":"2026-04-15T12:20:06.752401Z","end":"2026-04-15T12:20:08.557921Z","steps":["trace[203130493] 'agreement among raft nodes before linearized reading' (duration: 1.805369439s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.55794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:07.533059Z","time spent":"1.024873784s","remote":"127.0.0.1:51888","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-04-15T12:20:08.557725Z","caller":"traceutil/trace.go:171","msg":"trace[275715459] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1366; }","duration":"1.210662763s","start":"2026-04-15T12:20:07.347029Z","end":"2026-04-15T12:20:08.557692Z","steps":["trace[275715459] 'agreement among raft nodes before linearized reading' (duration: 1.210491699s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.558003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.752386Z","time spent":"1.805597805s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T12:20:08.558025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:07.346984Z","time spent":"1.211024422s","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:20:08.557742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.014357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:20:08.558171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.269478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-15T12:20:08.55821Z","caller":"traceutil/trace.go:171","msg":"trace[1561050944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"253.52006ms","start":"2026-04-15T12:20:08.304675Z","end":"2026-04-15T12:20:08.558195Z","steps":["trace[1561050944] 'agreement among raft nodes before linearized reading' (duration: 253.038208ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:20:08.558215Z","caller":"traceutil/trace.go:171","msg":"trace[243606315] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1366; }","duration":"759.36211ms","start":"2026-04-15T12:20:07.798841Z","end":"2026-04-15T12:20:08.558203Z","steps":["trace[243606315] 'agreement among raft nodes before linearized reading' (duration: 758.922819ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:20:08.55774Z","caller":"traceutil/trace.go:171","msg":"trace[1502685302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1366; }","duration":"1.833945424s","start":"2026-04-15T12:20:06.723776Z","end":"2026-04-15T12:20:08.557721Z","steps":["trace[1502685302] 'agreement among raft nodes before linearized reading' (duration: 1.833662827s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.558255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:07.798817Z","time spent":"759.431431ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-04-15T12:20:08.558292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.723746Z","time spent":"1.834529799s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T12:20:08.558303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807162155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-15T12:20:08.558314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056754573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-15T12:20:08.557676Z","caller":"traceutil/trace.go:171","msg":"trace[1391633473] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1366; }","duration":"991.24781ms","start":"2026-04-15T12:20:07.56641Z","end":"2026-04-15T12:20:08.557658Z","steps":["trace[1391633473] 'agreement among raft nodes before linearized reading' (duration: 991.075476ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:20:08.558407Z","caller":"traceutil/trace.go:171","msg":"trace[1775734283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"1.807328089s","start":"2026-04-15T12:20:06.751056Z","end":"2026-04-15T12:20:08.558384Z","steps":["trace[1775734283] 'agreement among raft nodes before linearized reading' (duration: 1.806795945s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.558463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:07.566397Z","time spent":"992.045241ms","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T12:20:08.558463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.75103Z","time spent":"1.807423512s","remote":"127.0.0.1:51888","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-04-15T12:20:08.558535Z","caller":"traceutil/trace.go:171","msg":"trace[1905587802] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.942762958s","start":"2026-04-15T12:20:06.614529Z","end":"2026-04-15T12:20:08.557292Z","steps":["trace[1905587802] 'process raft request' (duration: 1.941899607s)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:20:08.558449Z","caller":"traceutil/trace.go:171","msg":"trace[1573997587] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"1.057022752s","start":"2026-04-15T12:20:07.501404Z","end":"2026-04-15T12:20:08.558427Z","steps":["trace[1573997587] 'agreement among raft nodes before linearized reading' (duration: 1.056473567s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:20:08.558639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:07.501377Z","time spent":"1.057252557s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-15T12:20:08.558659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:20:06.6145Z","time spent":"1.944068331s","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:21:29.098694Z","caller":"traceutil/trace.go:171","msg":"trace[452497786] linearizableReadLoop","detail":"{readStateIndex:1811; appliedIndex:1808; }","duration":"131.487828ms","start":"2026-04-15T12:21:28.967183Z","end":"2026-04-15T12:21:29.098671Z","steps":["trace[452497786] 'read index received' (duration: 8.508594ms)","trace[452497786] 'applied index is now lower than readState.Index' (duration: 122.977804ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:21:29.099029Z","caller":"traceutil/trace.go:171","msg":"trace[1768644211] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"135.103989ms","start":"2026-04-15T12:21:28.96391Z","end":"2026-04-15T12:21:29.099014Z","steps":["trace[1768644211] 'process raft request' (duration: 134.710689ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:21:29.099025Z","caller":"traceutil/trace.go:171","msg":"trace[1748006539] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"136.039054ms","start":"2026-04-15T12:21:28.96296Z","end":"2026-04-15T12:21:29.098999Z","steps":["trace[1748006539] 'process raft request' (duration: 108.816427ms)","trace[1748006539] 'compare' (duration: 26.725214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:21:29.099188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.246317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-15T12:21:29.099183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.95736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:21:29.099271Z","caller":"traceutil/trace.go:171","msg":"trace[1526031808] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1740; }","duration":"130.36289ms","start":"2026-04-15T12:21:28.96889Z","end":"2026-04-15T12:21:29.099253Z","steps":["trace[1526031808] 'agreement among raft nodes before linearized reading' (duration: 130.093213ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:21:29.099304Z","caller":"traceutil/trace.go:171","msg":"trace[1210486269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1740; }","duration":"132.124434ms","start":"2026-04-15T12:21:28.96715Z","end":"2026-04-15T12:21:29.099275Z","steps":["trace[1210486269] 'agreement among raft nodes before linearized reading' (duration: 131.766195ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:21:31.316657Z","caller":"traceutil/trace.go:171","msg":"trace[1331798523] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1820; }","duration":"157.217807ms","start":"2026-04-15T12:21:31.159415Z","end":"2026-04-15T12:21:31.316633Z","steps":["trace[1331798523] 'read index received' (duration: 114.175662ms)","trace[1331798523] 'applied index is now lower than readState.Index' (duration: 43.041115ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:21:31.316771Z","caller":"traceutil/trace.go:171","msg":"trace[959826678] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"165.141296ms","start":"2026-04-15T12:21:31.151596Z","end":"2026-04-15T12:21:31.316738Z","steps":["trace[959826678] 'process raft request' (duration: 121.995898ms)","trace[959826678] 'compare' (duration: 42.903002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:21:31.317115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.581427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T12:21:31.317152Z","caller":"traceutil/trace.go:171","msg":"trace[2130467011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1750; }","duration":"157.777102ms","start":"2026-04-15T12:21:31.159365Z","end":"2026-04-15T12:21:31.317142Z","steps":["trace[2130467011] 'agreement among raft nodes before linearized reading' (duration: 157.413652ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:21:31.487873Z","caller":"traceutil/trace.go:171","msg":"trace[1644089538] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1821; }","duration":"136.667528ms","start":"2026-04-15T12:21:31.351174Z","end":"2026-04-15T12:21:31.487841Z","steps":["trace[1644089538] 'read index received' (duration: 85.764443ms)","trace[1644089538] 'applied index is now lower than readState.Index' (duration: 50.901745ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:21:31.487959Z","caller":"traceutil/trace.go:171","msg":"trace[191971438] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"165.63304ms","start":"2026-04-15T12:21:31.322285Z","end":"2026-04-15T12:21:31.487918Z","steps":["trace[191971438] 'process raft request' (duration: 114.778356ms)","trace[191971438] 'compare' (duration: 50.245598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:21:31.488086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.898285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-15T12:21:31.488626Z","caller":"traceutil/trace.go:171","msg":"trace[1909905539] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1751; }","duration":"137.481619ms","start":"2026-04-15T12:21:31.351116Z","end":"2026-04-15T12:21:31.488598Z","steps":["trace[1909905539] 'agreement among raft nodes before linearized reading' (duration: 136.869674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:22:09.42717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.886063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T12:22:09.427476Z","caller":"traceutil/trace.go:171","msg":"trace[1725410538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1921; }","duration":"129.241152ms","start":"2026-04-15T12:22:09.29821Z","end":"2026-04-15T12:22:09.427451Z","steps":["trace[1725410538] 'range keys from in-memory index tree' (duration: 128.725489ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:22:11.608488Z","caller":"traceutil/trace.go:171","msg":"trace[982188222] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"143.899821ms","start":"2026-04-15T12:22:11.46456Z","end":"2026-04-15T12:22:11.60846Z","steps":["trace[982188222] 'process raft request' (duration: 131.988591ms)","trace[982188222] 'compare' (duration: 11.705385ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:22:11.608807Z","caller":"traceutil/trace.go:171","msg":"trace[1507906332] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"144.059304ms","start":"2026-04-15T12:22:11.464736Z","end":"2026-04-15T12:22:11.608796Z","steps":["trace[1507906332] 'process raft request' (duration: 143.645584ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:07.142776Z","caller":"traceutil/trace.go:171","msg":"trace[538196410] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"165.759809ms","start":"2026-04-15T12:23:06.976992Z","end":"2026-04-15T12:23:07.142752Z","steps":["trace[538196410] 'read index received' (duration: 165.546113ms)","trace[538196410] 'applied index is now lower than readState.Index' (duration: 212.246µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:23:07.142816Z","caller":"traceutil/trace.go:171","msg":"trace[1382185480] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"219.850896ms","start":"2026-04-15T12:23:06.922939Z","end":"2026-04-15T12:23:07.14279Z","steps":["trace[1382185480] 'process raft request' (duration: 219.681132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:07.142905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.897473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T12:23:07.142939Z","caller":"traceutil/trace.go:171","msg":"trace[533866643] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2187; }","duration":"165.990805ms","start":"2026-04-15T12:23:06.976938Z","end":"2026-04-15T12:23:07.142929Z","steps":["trace[533866643] 'agreement among raft nodes before linearized reading' (duration: 165.910613ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:08.053075Z","caller":"traceutil/trace.go:171","msg":"trace[1313792560] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2279; }","duration":"171.817642ms","start":"2026-04-15T12:23:07.881231Z","end":"2026-04-15T12:23:08.053049Z","steps":["trace[1313792560] 'read index received' (duration: 145.198349ms)","trace[1313792560] 'applied index is now lower than readState.Index' (duration: 26.617803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:23:08.053257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.009167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T12:23:08.0533Z","caller":"traceutil/trace.go:171","msg":"trace[1742930647] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:2189; }","duration":"172.12022ms","start":"2026-04-15T12:23:07.881167Z","end":"2026-04-15T12:23:08.053287Z","steps":["trace[1742930647] 'agreement among raft nodes before linearized reading' (duration: 172.028528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:08.053571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.433211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T12:23:08.053657Z","caller":"traceutil/trace.go:171","msg":"trace[2002999688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2189; }","duration":"148.547905ms","start":"2026-04-15T12:23:07.905091Z","end":"2026-04-15T12:23:08.053639Z","steps":["trace[2002999688] 'agreement among raft nodes before linearized reading' (duration: 148.34291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:08.053669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.807141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-15T12:23:08.053608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.475644ms","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-04-15T12:23:08.053926Z","caller":"traceutil/trace.go:171","msg":"trace[421016438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"154.834273ms","start":"2026-04-15T12:23:07.899023Z","end":"2026-04-15T12:23:08.053857Z","steps":["trace[421016438] 'agreement among raft nodes before linearized reading' (duration: 154.255579ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:08.053752Z","caller":"traceutil/trace.go:171","msg":"trace[1436965384] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2189; }","duration":"148.927934ms","start":"2026-04-15T12:23:07.904805Z","end":"2026-04-15T12:23:08.053733Z","steps":["trace[1436965384] 'agreement among raft nodes before linearized reading' (duration: 148.676518ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:20.885461Z","caller":"traceutil/trace.go:171","msg":"trace[1568714333] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"193.432898ms","start":"2026-04-15T12:23:20.691999Z","end":"2026-04-15T12:23:20.885432Z","steps":["trace[1568714333] 'read index received' (duration: 193.337406ms)","trace[1568714333] 'applied index is now lower than readState.Index' (duration: 93.852µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:23:20.885703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.679814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-15T12:23:20.885751Z","caller":"traceutil/trace.go:171","msg":"trace[583325975] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2275; }","duration":"193.780627ms","start":"2026-04-15T12:23:20.691956Z","end":"2026-04-15T12:23:20.885736Z","steps":["trace[583325975] 'agreement among raft nodes before linearized reading' (duration: 193.591522ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:20.88582Z","caller":"traceutil/trace.go:171","msg":"trace[653898568] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"204.025996ms","start":"2026-04-15T12:23:20.681766Z","end":"2026-04-15T12:23:20.885792Z","steps":["trace[653898568] 'process raft request' (duration: 203.501103ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:30.528118Z","caller":"traceutil/trace.go:171","msg":"trace[1282403165] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"286.879853ms","start":"2026-04-15T12:23:30.241215Z","end":"2026-04-15T12:23:30.528095Z","steps":["trace[1282403165] 'process raft request' (duration: 286.741289ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:30.641051Z","caller":"traceutil/trace.go:171","msg":"trace[254114234] linearizableReadLoop","detail":"{readStateIndex:2419; appliedIndex:2418; }","duration":"395.481439ms","start":"2026-04-15T12:23:30.24554Z","end":"2026-04-15T12:23:30.641021Z","steps":["trace[254114234] 'read index received' (duration: 282.580754ms)","trace[254114234] 'applied index is now lower than readState.Index' (duration: 112.899645ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T12:23:30.641098Z","caller":"traceutil/trace.go:171","msg":"trace[1613003226] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"396.829923ms","start":"2026-04-15T12:23:30.244235Z","end":"2026-04-15T12:23:30.641065Z","steps":["trace[1613003226] 'process raft request' (duration: 308.703495ms)","trace[1613003226] 'compare' (duration: 87.961144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T12:23:30.64123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.82802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T12:23:30.641244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:23:30.244224Z","time spent":"396.939475ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T12:23:30.641268Z","caller":"traceutil/trace.go:171","msg":"trace[222231921] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2323; }","duration":"388.931262ms","start":"2026-04-15T12:23:30.252326Z","end":"2026-04-15T12:23:30.641257Z","steps":["trace[222231921] 'agreement among raft nodes before linearized reading' (duration: 388.84968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:30.641277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.731395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-15T12:23:30.641305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:23:30.252297Z","time spent":"388.999814ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-15T12:23:30.64134Z","caller":"traceutil/trace.go:171","msg":"trace[2109326880] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2323; }","duration":"395.812807ms","start":"2026-04-15T12:23:30.245513Z","end":"2026-04-15T12:23:30.641326Z","steps":["trace[2109326880] 'agreement among raft nodes before linearized reading' (duration: 395.633963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:30.641376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T12:23:30.245506Z","time spent":"395.862398ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-15T12:23:30.641527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.397048ms","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-04-15T12:23:30.641556Z","caller":"traceutil/trace.go:171","msg":"trace[391246483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2323; }","duration":"221.48183ms","start":"2026-04-15T12:23:30.420065Z","end":"2026-04-15T12:23:30.641547Z","steps":["trace[391246483] 'agreement among raft nodes before linearized reading' (duration: 221.394298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T12:23:30.641612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.963991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-15T12:23:30.641679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.594645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-15T12:23:30.641714Z","caller":"traceutil/trace.go:171","msg":"trace[1614886181] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2323; }","duration":"218.149455ms","start":"2026-04-15T12:23:30.423545Z","end":"2026-04-15T12:23:30.641695Z","steps":["trace[1614886181] 'agreement among raft nodes before linearized reading' (duration: 217.840387ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T12:23:30.641718Z","caller":"traceutil/trace.go:171","msg":"trace[909524594] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2323; }","duration":"297.664687ms","start":"2026-04-15T12:23:30.344041Z","end":"2026-04-15T12:23:30.641705Z","steps":["trace[909524594] 'agreement among raft nodes before linearized reading' (duration: 297.563794ms)"],"step_count":1}