{"level":"warn","ts":"2026-04-21T19:11:52.303275Z","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-21T19:11:52.3034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-21T19:11:52.303478Z","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-21T19:11:52.30349Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-21T19:11:52.303512Z","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-21T19:11:52.304155Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-21T19:11:52.304234Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-21T19:11:52.309867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.218831ms"} {"level":"info","ts":"2026-04-21T19:11:52.319788Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-21T19:11:52.319869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T19:11:52.319894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-21T19:11:52.31991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T19:11:52.319922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-21T19:11:52.319992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-21T19:11:52.330846Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T19:11:52.332414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T19:11:52.33373Z","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-21T19:11:52.335855Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T19:11:52.336058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T19:11:52.336264Z","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-21T19:11:52.337179Z","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-21T19:11:52.337241Z","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-21T19:11:52.337813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-21T19:11:52.33807Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-21T19:11:52.340434Z","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-21T19:11:52.340726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T19:11:52.34077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T19:11:52.340586Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-21T19:11:52.341435Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-21T19:11:53.32121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T19:11:53.321268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T19:11:53.32129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-21T19:11:53.321302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-21T19:11:53.321308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-21T19:11:53.321318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-21T19:11:53.321326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-21T19:11:53.322168Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T19:11:53.322285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T19:11:53.322325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T19:11:53.322422Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:11:53.322885Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T19:11:53.322911Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T19:11:53.326892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-21T19:11:53.330382Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:11:53.330547Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:11:53.330611Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:11:53.331264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-21T19:11:58.475391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.779114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-04-21T19:11:58.475507Z","caller":"traceutil/trace.go:171","msg":"trace[633677507] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"142.903065ms","start":"2026-04-21T19:11:58.332583Z","end":"2026-04-21T19:11:58.475486Z","steps":["trace[633677507] 'agreement among raft nodes before linearized reading' (duration: 142.729223ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.605461Z","caller":"traceutil/trace.go:171","msg":"trace[1773687317] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:290; }","duration":"130.152946ms","start":"2026-04-21T19:11:58.475287Z","end":"2026-04-21T19:11:58.60544Z","steps":["trace[1773687317] 'read index received' (duration: 47.655775ms)","trace[1773687317] 'applied index is now lower than readState.Index' (duration: 82.496041ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:11:58.605486Z","caller":"traceutil/trace.go:171","msg":"trace[2107432064] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"184.711945ms","start":"2026-04-21T19:11:58.42075Z","end":"2026-04-21T19:11:58.605462Z","steps":["trace[2107432064] 'process raft request' (duration: 184.643934ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.60552Z","caller":"traceutil/trace.go:171","msg":"trace[1912576604] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"186.534639ms","start":"2026-04-21T19:11:58.41894Z","end":"2026-04-21T19:11:58.605475Z","steps":["trace[1912576604] 'process raft request' (duration: 103.940587ms)","trace[1912576604] 'compare' (duration: 82.39209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:11:58.605651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.543731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T19:11:58.605736Z","caller":"traceutil/trace.go:171","msg":"trace[1875586045] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:285; }","duration":"185.678871ms","start":"2026-04-21T19:11:58.420038Z","end":"2026-04-21T19:11:58.605717Z","steps":["trace[1875586045] 'agreement among raft nodes before linearized reading' (duration: 185.49415ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647006Z","caller":"traceutil/trace.go:171","msg":"trace[1427158359] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"168.44265ms","start":"2026-04-21T19:11:58.478545Z","end":"2026-04-21T19:11:58.646987Z","steps":["trace[1427158359] 'process raft request' (duration: 168.084917ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647067Z","caller":"traceutil/trace.go:171","msg":"trace[385217177] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"168.877793ms","start":"2026-04-21T19:11:58.478153Z","end":"2026-04-21T19:11:58.64703Z","steps":["trace[385217177] 'process raft request' (duration: 168.390199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:11:58.646984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.783487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-04-21T19:11:58.647214Z","caller":"traceutil/trace.go:171","msg":"trace[1055262035] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:291; }","duration":"171.0537ms","start":"2026-04-21T19:11:58.476136Z","end":"2026-04-21T19:11:58.647189Z","steps":["trace[1055262035] 'agreement among raft nodes before linearized reading' (duration: 170.714188ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647204Z","caller":"traceutil/trace.go:171","msg":"trace[2039512951] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"168.44905ms","start":"2026-04-21T19:11:58.47874Z","end":"2026-04-21T19:11:58.647189Z","steps":["trace[2039512951] 'process raft request' (duration: 168.013127ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647128Z","caller":"traceutil/trace.go:171","msg":"trace[738862857] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"168.662421ms","start":"2026-04-21T19:11:58.47844Z","end":"2026-04-21T19:11:58.647102Z","steps":["trace[738862857] 'process raft request' (duration: 168.145688ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647312Z","caller":"traceutil/trace.go:171","msg":"trace[284607854] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"167.943746ms","start":"2026-04-21T19:11:58.47924Z","end":"2026-04-21T19:11:58.647184Z","steps":["trace[284607854] 'process raft request' (duration: 167.555443ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.647332Z","caller":"traceutil/trace.go:171","msg":"trace[1569200986] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"169.006973ms","start":"2026-04-21T19:11:58.478071Z","end":"2026-04-21T19:11:58.647078Z","steps":["trace[1569200986] 'process raft request' (duration: 168.292088ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.815243Z","caller":"traceutil/trace.go:171","msg":"trace[344345433] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:298; }","duration":"164.419519ms","start":"2026-04-21T19:11:58.650799Z","end":"2026-04-21T19:11:58.815218Z","steps":["trace[344345433] 'read index received' (duration: 115.639545ms)","trace[344345433] 'applied index is now lower than readState.Index' (duration: 48.778734ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:11:58.815469Z","caller":"traceutil/trace.go:171","msg":"trace[223688380] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"202.809923ms","start":"2026-04-21T19:11:58.612644Z","end":"2026-04-21T19:11:58.815454Z","steps":["trace[223688380] 'process raft request' (duration: 202.533441ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:11:58.815323Z","caller":"traceutil/trace.go:171","msg":"trace[1746594459] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"205.540074ms","start":"2026-04-21T19:11:58.609757Z","end":"2026-04-21T19:11:58.815297Z","steps":["trace[1746594459] 'process raft request' (duration: 156.77817ms)","trace[1746594459] 'compare' (duration: 48.423871ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:11:58.815756Z","caller":"traceutil/trace.go:171","msg":"trace[1165905273] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"205.703745ms","start":"2026-04-21T19:11:58.61003Z","end":"2026-04-21T19:11:58.815734Z","steps":["trace[1165905273] 'process raft request' (duration: 205.08039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:11:58.815984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.100615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-04-21T19:11:58.81604Z","caller":"traceutil/trace.go:171","msg":"trace[284237802] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:294; }","duration":"165.239316ms","start":"2026-04-21T19:11:58.650786Z","end":"2026-04-21T19:11:58.816025Z","steps":["trace[284237802] 'agreement among raft nodes before linearized reading' (duration: 164.815003ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:03.518065Z","caller":"traceutil/trace.go:171","msg":"trace[187683225] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"143.289094ms","start":"2026-04-21T19:12:03.374748Z","end":"2026-04-21T19:12:03.518037Z","steps":["trace[187683225] 'process raft request' (duration: 143.117843ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:03.693566Z","caller":"traceutil/trace.go:171","msg":"trace[120762587] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"166.421777ms","start":"2026-04-21T19:12:03.527038Z","end":"2026-04-21T19:12:03.69346Z","steps":["trace[120762587] 'process raft request' (duration: 161.590111ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:03.870989Z","caller":"traceutil/trace.go:171","msg":"trace[1264400085] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"106.719223ms","start":"2026-04-21T19:12:03.76424Z","end":"2026-04-21T19:12:03.870959Z","steps":["trace[1264400085] 'process raft request' (duration: 35.896306ms)","trace[1264400085] 'compare' (duration: 70.705596ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:08.009297Z","caller":"traceutil/trace.go:171","msg":"trace[349590427] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"119.565749ms","start":"2026-04-21T19:12:07.889708Z","end":"2026-04-21T19:12:08.009274Z","steps":["trace[349590427] 'process raft request' (duration: 119.360898ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:09.08136Z","caller":"traceutil/trace.go:171","msg":"trace[1085723823] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"145.669182ms","start":"2026-04-21T19:12:08.935666Z","end":"2026-04-21T19:12:09.081335Z","steps":["trace[1085723823] 'process raft request' (duration: 145.502031ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:09.082371Z","caller":"traceutil/trace.go:171","msg":"trace[378413261] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:410; }","duration":"119.810204ms","start":"2026-04-21T19:12:08.962546Z","end":"2026-04-21T19:12:09.082356Z","steps":["trace[378413261] 'read index received' (duration: 118.626666ms)","trace[378413261] 'applied index is now lower than readState.Index' (duration: 1.182638ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:09.082556Z","caller":"traceutil/trace.go:171","msg":"trace[573848911] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"140.999758ms","start":"2026-04-21T19:12:08.941545Z","end":"2026-04-21T19:12:09.082544Z","steps":["trace[573848911] 'process raft request' (duration: 140.750726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:09.082714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.163357ms","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-21T19:12:09.082756Z","caller":"traceutil/trace.go:171","msg":"trace[1577989802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"120.258018ms","start":"2026-04-21T19:12:08.962486Z","end":"2026-04-21T19:12:09.082744Z","steps":["trace[1577989802] 'agreement among raft nodes before linearized reading' (duration: 120.182208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:09.082905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.009477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-21T19:12:09.082938Z","caller":"traceutil/trace.go:171","msg":"trace[765474393] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:402; }","duration":"105.071997ms","start":"2026-04-21T19:12:08.977858Z","end":"2026-04-21T19:12:09.08293Z","steps":["trace[765474393] 'agreement among raft nodes before linearized reading' (duration: 105.018157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:09.40969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.029662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:09.409905Z","caller":"traceutil/trace.go:171","msg":"trace[307317269] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"319.153139ms","start":"2026-04-21T19:12:09.090709Z","end":"2026-04-21T19:12:09.409862Z","steps":["trace[307317269] 'process raft request' (duration: 147.243231ms)","trace[307317269] 'compare' (duration: 170.948091ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:09.4101Z","caller":"traceutil/trace.go:171","msg":"trace[1670362288] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"318.977278ms","start":"2026-04-21T19:12:09.091106Z","end":"2026-04-21T19:12:09.410083Z","steps":["trace[1670362288] 'process raft request' (duration: 318.698076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:09.410062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:09.090691Z","time spent":"319.29311ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:09.410186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:09.091071Z","time spent":"319.073699ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:10.233117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.993117ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:10.233452Z","caller":"traceutil/trace.go:171","msg":"trace[934225418] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"114.35451ms","start":"2026-04-21T19:12:10.119055Z","end":"2026-04-21T19:12:10.233409Z","steps":["trace[934225418] 'range keys from in-memory index tree' (duration: 113.918717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:10.495903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.42031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:10.496067Z","caller":"traceutil/trace.go:171","msg":"trace[367104081] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"171.250319ms","start":"2026-04-21T19:12:10.324794Z","end":"2026-04-21T19:12:10.496044Z","steps":["trace[367104081] 'read index received' (duration: 56.515486ms)","trace[367104081] 'applied index is now lower than readState.Index' (duration: 114.733563ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:10.49617Z","caller":"traceutil/trace.go:171","msg":"trace[264148650] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"243.402485ms","start":"2026-04-21T19:12:10.252731Z","end":"2026-04-21T19:12:10.496133Z","steps":["trace[264148650] 'process raft request' (duration: 128.635122ms)","trace[264148650] 'compare' (duration: 114.205708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:10.496416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.593242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-21T19:12:10.496576Z","caller":"traceutil/trace.go:171","msg":"trace[667101796] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:409; }","duration":"171.807203ms","start":"2026-04-21T19:12:10.324743Z","end":"2026-04-21T19:12:10.49655Z","steps":["trace[667101796] 'agreement among raft nodes before linearized reading' (duration: 171.42002ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:10.681748Z","caller":"traceutil/trace.go:171","msg":"trace[1113182173] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"175.601589ms","start":"2026-04-21T19:12:10.506111Z","end":"2026-04-21T19:12:10.681712Z","steps":["trace[1113182173] 'read index received' (duration: 174.829284ms)","trace[1113182173] 'applied index is now lower than readState.Index' (duration: 770.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:10.681872Z","caller":"traceutil/trace.go:171","msg":"trace[1422585633] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"176.659907ms","start":"2026-04-21T19:12:10.505159Z","end":"2026-04-21T19:12:10.681819Z","steps":["trace[1422585633] 'process raft request' (duration: 175.763841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:10.682063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.916641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:10.682109Z","caller":"traceutil/trace.go:171","msg":"trace[785537531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"176.025363ms","start":"2026-04-21T19:12:10.506069Z","end":"2026-04-21T19:12:10.682094Z","steps":["trace[785537531] 'agreement among raft nodes before linearized reading' (duration: 175.785961ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:10.92763Z","caller":"traceutil/trace.go:171","msg":"trace[319434454] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"421.306501ms","start":"2026-04-21T19:12:10.506282Z","end":"2026-04-21T19:12:10.927589Z","steps":["trace[319434454] 'process raft request' (duration: 327.926281ms)","trace[319434454] 'compare' (duration: 93.218809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:10.927886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:10.506269Z","time spent":"421.487352ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:10.928308Z","caller":"traceutil/trace.go:171","msg":"trace[1474110504] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"240.973078ms","start":"2026-04-21T19:12:10.687297Z","end":"2026-04-21T19:12:10.92827Z","steps":["trace[1474110504] 'process raft request' (duration: 240.728076ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:10.928403Z","caller":"traceutil/trace.go:171","msg":"trace[322839182] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"239.79245ms","start":"2026-04-21T19:12:10.688595Z","end":"2026-04-21T19:12:10.928387Z","steps":["trace[322839182] 'process raft request' (duration: 239.604938ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:10.928407Z","caller":"traceutil/trace.go:171","msg":"trace[1547538888] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"239.599638ms","start":"2026-04-21T19:12:10.688768Z","end":"2026-04-21T19:12:10.928368Z","steps":["trace[1547538888] 'process raft request' (duration: 239.495808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.806989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.136825ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T19:12:17.807079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53890564s","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-21T19:12:17.807131Z","caller":"traceutil/trace.go:171","msg":"trace[792239785] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:452; }","duration":"186.293106ms","start":"2026-04-21T19:12:17.62081Z","end":"2026-04-21T19:12:17.807103Z","steps":["trace[792239785] 'range keys from in-memory index tree' (duration: 186.108974ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:17.807195Z","caller":"traceutil/trace.go:171","msg":"trace[1374987450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"1.539009681s","start":"2026-04-21T19:12:16.268133Z","end":"2026-04-21T19:12:17.807143Z","steps":["trace[1374987450] 'range keys from in-memory index tree' (duration: 1.53881581s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.807249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:16.268116Z","time spent":"1.539124663s","remote":"127.0.0.1:35036","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-21T19:12:17.807448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.819163ms","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-21T19:12:17.807505Z","caller":"traceutil/trace.go:171","msg":"trace[1669426530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:452; }","duration":"649.898163ms","start":"2026-04-21T19:12:17.157591Z","end":"2026-04-21T19:12:17.807489Z","steps":["trace[1669426530] 'range keys from in-memory index tree' (duration: 649.748012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.807555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:17.157578Z","time spent":"649.963674ms","remote":"127.0.0.1:35036","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-21T19:12:17.807624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426887978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T19:12:17.807659Z","caller":"traceutil/trace.go:171","msg":"trace[195256298] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:452; }","duration":"1.426921788s","start":"2026-04-21T19:12:16.380727Z","end":"2026-04-21T19:12:17.807649Z","steps":["trace[195256298] 'range keys from in-memory index tree' (duration: 1.426676117s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.807686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:16.380665Z","time spent":"1.427014419s","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-21T19:12:17.807935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.518023ms","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-21T19:12:17.807992Z","caller":"traceutil/trace.go:171","msg":"trace[364327352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:452; }","duration":"359.605613ms","start":"2026-04-21T19:12:17.448372Z","end":"2026-04-21T19:12:17.807978Z","steps":["trace[364327352] 'range keys from in-memory index tree' (duration: 359.369182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.807024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276630847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:17.808036Z","caller":"traceutil/trace.go:171","msg":"trace[211458058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"1.277703276s","start":"2026-04-21T19:12:16.530322Z","end":"2026-04-21T19:12:17.808025Z","steps":["trace[211458058] 'range keys from in-memory index tree' (duration: 1.276563447s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:17.808048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:17.448355Z","time spent":"359.675374ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T19:12:17.808072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:16.530305Z","time spent":"1.277756786s","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T19:12:19.552839Z","caller":"traceutil/trace.go:171","msg":"trace[611618087] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"148.266361ms","start":"2026-04-21T19:12:19.404527Z","end":"2026-04-21T19:12:19.552794Z","steps":["trace[611618087] 'process raft request' (duration: 148.067343ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:20.035018Z","caller":"traceutil/trace.go:171","msg":"trace[928353675] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"104.268032ms","start":"2026-04-21T19:12:19.930726Z","end":"2026-04-21T19:12:20.034994Z","steps":["trace[928353675] 'process raft request' (duration: 104.149958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:20.581943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.857622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:20.582116Z","caller":"traceutil/trace.go:171","msg":"trace[173889238] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:471; }","duration":"512.942533ms","start":"2026-04-21T19:12:20.069154Z","end":"2026-04-21T19:12:20.582097Z","steps":["trace[173889238] 'read index received' (duration: 281.774368ms)","trace[173889238] 'applied index is now lower than readState.Index' (duration: 231.166985ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:20.582712Z","caller":"traceutil/trace.go:171","msg":"trace[235570279] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"651.684639ms","start":"2026-04-21T19:12:19.93101Z","end":"2026-04-21T19:12:20.582695Z","steps":["trace[235570279] 'process raft request' (duration: 419.969102ms)","trace[235570279] 'compare' (duration: 230.54995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:20.582793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:19.930993Z","time spent":"651.760681ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:20.583041Z","caller":"traceutil/trace.go:171","msg":"trace[1059758204] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"651.845455ms","start":"2026-04-21T19:12:19.931183Z","end":"2026-04-21T19:12:20.583029Z","steps":["trace[1059758204] 'process raft request' (duration: 650.853565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:20.583112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:19.931176Z","time spent":"651.899847ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:20.583342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.183333ms","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-21T19:12:20.583382Z","caller":"traceutil/trace.go:171","msg":"trace[583502218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:461; }","duration":"514.254876ms","start":"2026-04-21T19:12:20.069116Z","end":"2026-04-21T19:12:20.583371Z","steps":["trace[583502218] 'agreement among raft nodes before linearized reading' (duration: 514.181112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:20.583413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:20.069101Z","time spent":"514.304939ms","remote":"127.0.0.1:35036","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-21T19:12:22.117831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.893476ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T19:12:22.118492Z","caller":"traceutil/trace.go:171","msg":"trace[1307752084] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"114.369179ms","start":"2026-04-21T19:12:22.004104Z","end":"2026-04-21T19:12:22.118473Z","steps":["trace[1307752084] 'read index received' (duration: 37.092µs)","trace[1307752084] 'applied index is now lower than readState.Index' (duration: 114.313517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:22.118614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.504445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:22.118646Z","caller":"traceutil/trace.go:171","msg":"trace[590729426] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:462; }","duration":"114.583819ms","start":"2026-04-21T19:12:22.004053Z","end":"2026-04-21T19:12:22.118637Z","steps":["trace[590729426] 'agreement among raft nodes before linearized reading' (duration: 114.513036ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:22.416007Z","caller":"traceutil/trace.go:171","msg":"trace[1291443580] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"126.323405ms","start":"2026-04-21T19:12:22.289634Z","end":"2026-04-21T19:12:22.415957Z","steps":["trace[1291443580] 'process raft request' (duration: 125.602545ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:22.80339Z","caller":"traceutil/trace.go:171","msg":"trace[952168990] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"177.052603ms","start":"2026-04-21T19:12:22.626296Z","end":"2026-04-21T19:12:22.803348Z","steps":["trace[952168990] 'process raft request' (duration: 121.662189ms)","trace[952168990] 'compare' (duration: 55.199366ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:22.803582Z","caller":"traceutil/trace.go:171","msg":"trace[2077301374] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"176.679688ms","start":"2026-04-21T19:12:22.626891Z","end":"2026-04-21T19:12:22.803571Z","steps":["trace[2077301374] 'process raft request' (duration: 176.577234ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:22.803564Z","caller":"traceutil/trace.go:171","msg":"trace[1426443722] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:482; }","duration":"162.328647ms","start":"2026-04-21T19:12:22.641211Z","end":"2026-04-21T19:12:22.803539Z","steps":["trace[1426443722] 'read index received' (duration: 106.817269ms)","trace[1426443722] 'applied index is now lower than readState.Index' (duration: 55.510298ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:22.803659Z","caller":"traceutil/trace.go:171","msg":"trace[1506870631] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"177.155997ms","start":"2026-04-21T19:12:22.626495Z","end":"2026-04-21T19:12:22.803651Z","steps":["trace[1506870631] 'process raft request' (duration: 176.785142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:22.803694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.466253ms","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-21T19:12:22.803727Z","caller":"traceutil/trace.go:171","msg":"trace[920960746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"162.538825ms","start":"2026-04-21T19:12:22.641178Z","end":"2026-04-21T19:12:22.803717Z","steps":["trace[920960746] 'agreement among raft nodes before linearized reading' (duration: 162.443972ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:22.803815Z","caller":"traceutil/trace.go:171","msg":"trace[1751670135] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"176.653047ms","start":"2026-04-21T19:12:22.627153Z","end":"2026-04-21T19:12:22.803806Z","steps":["trace[1751670135] 'process raft request' (duration: 176.357785ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.07769Z","caller":"traceutil/trace.go:171","msg":"trace[259645757] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"113.839935ms","start":"2026-04-21T19:12:22.963826Z","end":"2026-04-21T19:12:23.077666Z","steps":["trace[259645757] 'process raft request' (duration: 113.337105ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.395261Z","caller":"traceutil/trace.go:171","msg":"trace[822452143] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"253.451814ms","start":"2026-04-21T19:12:23.141773Z","end":"2026-04-21T19:12:23.395225Z","steps":["trace[822452143] 'process raft request' (duration: 193.135343ms)","trace[822452143] 'compare' (duration: 60.084762ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:23.395285Z","caller":"traceutil/trace.go:171","msg":"trace[1844257387] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"248.836263ms","start":"2026-04-21T19:12:23.146424Z","end":"2026-04-21T19:12:23.39526Z","steps":["trace[1844257387] 'process raft request' (duration: 248.777091ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.395543Z","caller":"traceutil/trace.go:171","msg":"trace[1202927582] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"253.230515ms","start":"2026-04-21T19:12:23.142267Z","end":"2026-04-21T19:12:23.395497Z","steps":["trace[1202927582] 'process raft request' (duration: 252.864161ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.416726Z","caller":"traceutil/trace.go:171","msg":"trace[1726158598] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"244.71154ms","start":"2026-04-21T19:12:23.17196Z","end":"2026-04-21T19:12:23.416672Z","steps":["trace[1726158598] 'process raft request' (duration: 244.012713ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.417266Z","caller":"traceutil/trace.go:171","msg":"trace[1650603049] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"241.072077ms","start":"2026-04-21T19:12:23.176165Z","end":"2026-04-21T19:12:23.417237Z","steps":["trace[1650603049] 'process raft request' (duration: 240.782786ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.417264Z","caller":"traceutil/trace.go:171","msg":"trace[441368428] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"244.45387ms","start":"2026-04-21T19:12:23.172626Z","end":"2026-04-21T19:12:23.41708Z","steps":["trace[441368428] 'process raft request' (duration: 243.811245ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.417658Z","caller":"traceutil/trace.go:171","msg":"trace[400636117] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:498; }","duration":"242.684371ms","start":"2026-04-21T19:12:23.174919Z","end":"2026-04-21T19:12:23.417604Z","steps":["trace[400636117] 'read index received' (duration: 160.004411ms)","trace[400636117] 'applied index is now lower than readState.Index' (duration: 82.677599ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:23.41792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.983882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-21T19:12:23.417996Z","caller":"traceutil/trace.go:171","msg":"trace[713969623] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:491; }","duration":"243.092736ms","start":"2026-04-21T19:12:23.174886Z","end":"2026-04-21T19:12:23.417978Z","steps":["trace[713969623] 'agreement among raft nodes before linearized reading' (duration: 242.902289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:23.492311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.454272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-21T19:12:23.492401Z","caller":"traceutil/trace.go:171","msg":"trace[188145087] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:493; }","duration":"228.606418ms","start":"2026-04-21T19:12:23.263773Z","end":"2026-04-21T19:12:23.49238Z","steps":["trace[188145087] 'agreement among raft nodes before linearized reading' (duration: 228.166721ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.594805Z","caller":"traceutil/trace.go:171","msg":"trace[893522115] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"168.840318ms","start":"2026-04-21T19:12:23.425939Z","end":"2026-04-21T19:12:23.594779Z","steps":["trace[893522115] 'process raft request' (duration: 168.765605ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.595116Z","caller":"traceutil/trace.go:171","msg":"trace[746975221] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"169.830497ms","start":"2026-04-21T19:12:23.425256Z","end":"2026-04-21T19:12:23.595086Z","steps":["trace[746975221] 'process raft request' (duration: 156.18673ms)","trace[746975221] 'compare' (duration: 13.115735ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:23.6169Z","caller":"traceutil/trace.go:171","msg":"trace[200903994] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"120.537859ms","start":"2026-04-21T19:12:23.49633Z","end":"2026-04-21T19:12:23.616867Z","steps":["trace[200903994] 'process raft request' (duration: 120.330561ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.616936Z","caller":"traceutil/trace.go:171","msg":"trace[2002861132] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"108.427822ms","start":"2026-04-21T19:12:23.508485Z","end":"2026-04-21T19:12:23.616912Z","steps":["trace[2002861132] 'process raft request' (duration: 108.272376ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:23.736885Z","caller":"traceutil/trace.go:171","msg":"trace[183617897] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"113.891397ms","start":"2026-04-21T19:12:23.622966Z","end":"2026-04-21T19:12:23.736857Z","steps":["trace[183617897] 'process raft request' (duration: 79.242585ms)","trace[183617897] 'compare' (duration: 34.529397ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:23.921447Z","caller":"traceutil/trace.go:171","msg":"trace[1200698773] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"240.13315ms","start":"2026-04-21T19:12:23.681289Z","end":"2026-04-21T19:12:23.921422Z","steps":["trace[1200698773] 'read index received' (duration: 21.006726ms)","trace[1200698773] 'applied index is now lower than readState.Index' (duration: 219.125484ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:23.921565Z","caller":"traceutil/trace.go:171","msg":"trace[1796927614] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"294.477266ms","start":"2026-04-21T19:12:23.627077Z","end":"2026-04-21T19:12:23.921554Z","steps":["trace[1796927614] 'process raft request' (duration: 250.822551ms)","trace[1796927614] 'compare' (duration: 43.424776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:23.921762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.456204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-21T19:12:23.921802Z","caller":"traceutil/trace.go:171","msg":"trace[1352299290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:499; }","duration":"240.544787ms","start":"2026-04-21T19:12:23.681246Z","end":"2026-04-21T19:12:23.921791Z","steps":["trace[1352299290] 'agreement among raft nodes before linearized reading' (duration: 240.460703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:23.922049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.611369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-21T19:12:23.922125Z","caller":"traceutil/trace.go:171","msg":"trace[617478165] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:499; }","duration":"240.724134ms","start":"2026-04-21T19:12:23.681382Z","end":"2026-04-21T19:12:23.922107Z","steps":["trace[617478165] 'agreement among raft nodes before linearized reading' (duration: 240.537377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:25.343851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195013,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T19:12:25.424425Z","caller":"traceutil/trace.go:171","msg":"trace[2101480284] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"611.887487ms","start":"2026-04-21T19:12:24.812518Z","end":"2026-04-21T19:12:25.424406Z","steps":["trace[2101480284] 'process raft request' (duration: 611.765772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:25.424575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.812499Z","time spent":"612.004422ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:28.528257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000036233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-21T19:12:28.528357Z","caller":"traceutil/trace.go:171","msg":"trace[2049859084] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000283982s","start":"2026-04-21T19:12:26.528051Z","end":"2026-04-21T19:12:28.528335Z","steps":["trace[2049859084] 'agreement among raft nodes before linearized reading' (duration: 2.000152117s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:28.528413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:26.528025Z","time spent":"2.000377266s","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:12:29.308601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.883513145s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T19:12:29.309188Z","caller":"traceutil/trace.go:171","msg":"trace[999742529] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"4.365261263s","start":"2026-04-21T19:12:24.943902Z","end":"2026-04-21T19:12:29.309164Z","steps":["trace[999742529] 'process raft request' (duration: 4.365188611s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:29.30921Z","caller":"traceutil/trace.go:171","msg":"trace[1807794316] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"4.495470262s","start":"2026-04-21T19:12:24.813699Z","end":"2026-04-21T19:12:29.30917Z","steps":["trace[1807794316] 'process raft request' (duration: 4.495245353s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.309327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.943883Z","time spent":"4.365378857s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.309395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.813689Z","time spent":"4.495597457s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:29.309762Z","caller":"traceutil/trace.go:171","msg":"trace[200872966] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"4.358817561s","start":"2026-04-21T19:12:24.950909Z","end":"2026-04-21T19:12:29.309726Z","steps":["trace[200872966] 'process raft request' (duration: 4.358594983s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:29.309838Z","caller":"traceutil/trace.go:171","msg":"trace[1579877919] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"4.360358541s","start":"2026-04-21T19:12:24.949465Z","end":"2026-04-21T19:12:29.309823Z","steps":["trace[1579877919] 'process raft request' (duration: 4.359669395s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:29.309904Z","caller":"traceutil/trace.go:171","msg":"trace[34722742] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"4.356841545s","start":"2026-04-21T19:12:24.953044Z","end":"2026-04-21T19:12:29.309885Z","steps":["trace[34722742] 'process raft request' (duration: 4.356531333s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.309916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.949445Z","time spent":"4.360441555s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.30992Z","caller":"traceutil/trace.go:171","msg":"trace[14797405] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"4.356813764s","start":"2026-04-21T19:12:24.953093Z","end":"2026-04-21T19:12:29.309907Z","steps":["trace[14797405] 'process raft request' (duration: 4.356518823s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.309976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.953088Z","time spent":"4.356860366s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.309992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.953036Z","time spent":"4.356904437s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.310102Z","caller":"traceutil/trace.go:171","msg":"trace[319780160] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"4.3564828s","start":"2026-04-21T19:12:24.953607Z","end":"2026-04-21T19:12:29.31009Z","steps":["trace[319780160] 'process raft request' (duration: 4.356031873s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.31019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.9536Z","time spent":"4.356533971s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.310248Z","caller":"traceutil/trace.go:171","msg":"trace[1950817304] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"4.354668859s","start":"2026-04-21T19:12:24.955568Z","end":"2026-04-21T19:12:29.310237Z","steps":["trace[1950817304] 'process raft request' (duration: 4.354096667s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.310299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.955561Z","time spent":"4.354715712s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.310324Z","caller":"traceutil/trace.go:171","msg":"trace[1041710974] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"4.354020485s","start":"2026-04-21T19:12:24.956292Z","end":"2026-04-21T19:12:29.310313Z","steps":["trace[1041710974] 'process raft request' (duration: 4.35387566s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:29.310383Z","caller":"traceutil/trace.go:171","msg":"trace[1335566833] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"4.350657453s","start":"2026-04-21T19:12:24.959717Z","end":"2026-04-21T19:12:29.310375Z","steps":["trace[1335566833] 'process raft request' (duration: 4.350488817s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:29.310386Z","caller":"traceutil/trace.go:171","msg":"trace[2052988419] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"4.338956228s","start":"2026-04-21T19:12:24.97142Z","end":"2026-04-21T19:12:29.310377Z","steps":["trace[2052988419] 'process raft request' (duration: 4.338857195s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.310427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.959705Z","time spent":"4.350704484s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.310448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.956285Z","time spent":"4.354063896s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.310432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.971408Z","time spent":"4.339005031s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.310193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.950896Z","time spent":"4.358930535s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.310683Z","caller":"traceutil/trace.go:171","msg":"trace[1589219135] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"4.320791743s","start":"2026-04-21T19:12:24.989882Z","end":"2026-04-21T19:12:29.310673Z","steps":["trace[1589219135] 'process raft request' (duration: 4.320449971s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.311934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.989866Z","time spent":"4.320845694s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:29.312333Z","caller":"traceutil/trace.go:171","msg":"trace[2000830093] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:513; }","duration":"4.468998267s","start":"2026-04-21T19:12:24.843266Z","end":"2026-04-21T19:12:29.312264Z","steps":["trace[2000830093] 'read index received' (duration: 581.105791ms)","trace[2000830093] 'applied index is now lower than readState.Index' (duration: 3.887890666s)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:29.313025Z","caller":"traceutil/trace.go:171","msg":"trace[1336645984] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"4.200935168s","start":"2026-04-21T19:12:25.112056Z","end":"2026-04-21T19:12:29.312991Z","steps":["trace[1336645984] 'process raft request' (duration: 4.198580402s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.313116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:25.112037Z","time spent":"4.201032912s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:29.317937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.474631874s","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-21T19:12:29.318069Z","caller":"traceutil/trace.go:171","msg":"trace[1895267771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"4.47480861s","start":"2026-04-21T19:12:24.843218Z","end":"2026-04-21T19:12:29.318026Z","steps":["trace[1895267771] 'agreement among raft nodes before linearized reading' (duration: 4.470523363s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:29.31812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:24.843202Z","time spent":"4.474907293s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T19:12:29.812857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T19:12:30.280194Z","caller":"traceutil/trace.go:171","msg":"trace[1444546714] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"4.843066888s","start":"2026-04-21T19:12:25.437091Z","end":"2026-04-21T19:12:30.280158Z","steps":["trace[1444546714] 'process raft request' (duration: 4.761924756s)","trace[1444546714] 'compare' (duration: 80.989456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:30.280378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:25.437068Z","time spent":"4.843213223s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:30.294837Z","caller":"traceutil/trace.go:171","msg":"trace[1431115720] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"968.792927ms","start":"2026-04-21T19:12:29.325982Z","end":"2026-04-21T19:12:30.294775Z","steps":["trace[1431115720] 'process raft request' (duration: 968.495716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.295025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.325965Z","time spent":"968.968143ms","remote":"127.0.0.1:35286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:30.295342Z","caller":"traceutil/trace.go:171","msg":"trace[130170460] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"967.627334ms","start":"2026-04-21T19:12:29.327701Z","end":"2026-04-21T19:12:30.295329Z","steps":["trace[130170460] 'process raft request' (duration: 967.277361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.295404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.327695Z","time spent":"967.673265ms","remote":"127.0.0.1:35286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:30.295576Z","caller":"traceutil/trace.go:171","msg":"trace[1495162347] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"968.462233ms","start":"2026-04-21T19:12:29.327103Z","end":"2026-04-21T19:12:30.295565Z","steps":["trace[1495162347] 'process raft request' (duration: 967.513179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.295644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.327087Z","time spent":"968.515705ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:30.295607Z","caller":"traceutil/trace.go:171","msg":"trace[131616814] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:527; }","duration":"983.212136ms","start":"2026-04-21T19:12:29.312367Z","end":"2026-04-21T19:12:30.295579Z","steps":["trace[131616814] 'read index received' (duration: 886.760539ms)","trace[131616814] 'applied index is now lower than readState.Index' (duration: 96.450546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:30.295793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.090685298s","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-21T19:12:30.29581Z","caller":"traceutil/trace.go:171","msg":"trace[226531976] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"959.495975ms","start":"2026-04-21T19:12:29.336288Z","end":"2026-04-21T19:12:30.295784Z","steps":["trace[226531976] 'process raft request' (duration: 959.032258ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.29585Z","caller":"traceutil/trace.go:171","msg":"trace[681031200] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"968.329839ms","start":"2026-04-21T19:12:29.32751Z","end":"2026-04-21T19:12:30.29584Z","steps":["trace[681031200] 'process raft request' (duration: 967.368174ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.295867Z","caller":"traceutil/trace.go:171","msg":"trace[1382971672] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"958.862111ms","start":"2026-04-21T19:12:29.336995Z","end":"2026-04-21T19:12:30.295857Z","steps":["trace[1382971672] 'process raft request' (duration: 958.438926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.295898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.327482Z","time spent":"968.393951ms","remote":"127.0.0.1:35286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:30.295919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.33628Z","time spent":"959.579158ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:30.295987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.336987Z","time spent":"958.924394ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:30.296013Z","caller":"traceutil/trace.go:171","msg":"trace[2009136476] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"968.34992ms","start":"2026-04-21T19:12:29.327654Z","end":"2026-04-21T19:12:30.296004Z","steps":["trace[2009136476] 'process raft request' (duration: 967.26951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.296061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.327645Z","time spent":"968.394032ms","remote":"127.0.0.1:35286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:30.2962Z","caller":"traceutil/trace.go:171","msg":"trace[1068728787] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"967.433706ms","start":"2026-04-21T19:12:29.328753Z","end":"2026-04-21T19:12:30.296187Z","steps":["trace[1068728787] 'process raft request' (duration: 966.270354ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.29624Z","caller":"traceutil/trace.go:171","msg":"trace[1146605785] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"930.443261ms","start":"2026-04-21T19:12:29.365786Z","end":"2026-04-21T19:12:30.296229Z","steps":["trace[1146605785] 'process raft request' (duration: 929.715574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.296264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.328744Z","time spent":"967.484109ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:30.296289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.365718Z","time spent":"930.548074ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:30.296426Z","caller":"traceutil/trace.go:171","msg":"trace[1715381782] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"964.611683ms","start":"2026-04-21T19:12:29.331804Z","end":"2026-04-21T19:12:30.296415Z","steps":["trace[1715381782] 'process raft request' (duration: 963.263693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.2965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.331783Z","time spent":"964.667025ms","remote":"127.0.0.1:35286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:30.296618Z","caller":"traceutil/trace.go:171","msg":"trace[1326138740] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"964.463777ms","start":"2026-04-21T19:12:29.332144Z","end":"2026-04-21T19:12:30.296608Z","steps":["trace[1326138740] 'process raft request' (duration: 962.960322ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.295828Z","caller":"traceutil/trace.go:171","msg":"trace[507286395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"4.090774081s","start":"2026-04-21T19:12:26.205041Z","end":"2026-04-21T19:12:30.295815Z","steps":["trace[507286395] 'agreement among raft nodes before linearized reading' (duration: 4.090660857s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.296675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.332136Z","time spent":"964.512109ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:30.296707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:26.20502Z","time spent":"4.091667664s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T19:12:30.29673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.915087881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T19:12:30.296776Z","caller":"traceutil/trace.go:171","msg":"trace[308342288] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:529; }","duration":"3.915179234s","start":"2026-04-21T19:12:26.381581Z","end":"2026-04-21T19:12:30.29676Z","steps":["trace[308342288] 'agreement among raft nodes before linearized reading' (duration: 3.915093531s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.296809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:26.381554Z","time spent":"3.915248526s","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"info","ts":"2026-04-21T19:12:30.296836Z","caller":"traceutil/trace.go:171","msg":"trace[544553657] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"964.629303ms","start":"2026-04-21T19:12:29.33219Z","end":"2026-04-21T19:12:30.296819Z","steps":["trace[544553657] 'process raft request' (duration: 963.016694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.296898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.332185Z","time spent":"964.676286ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:30.296923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768004996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T19:12:30.296997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.868420373s","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-21T19:12:30.297001Z","caller":"traceutil/trace.go:171","msg":"trace[63074131] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"961.652493ms","start":"2026-04-21T19:12:29.335338Z","end":"2026-04-21T19:12:30.29699Z","steps":["trace[63074131] 'process raft request' (duration: 959.92114ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.297009Z","caller":"traceutil/trace.go:171","msg":"trace[777256829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"1.768102019s","start":"2026-04-21T19:12:28.528884Z","end":"2026-04-21T19:12:30.296986Z","steps":["trace[777256829] 'agreement among raft nodes before linearized reading' (duration: 1.767948793s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.297035Z","caller":"traceutil/trace.go:171","msg":"trace[865457690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"2.868490315s","start":"2026-04-21T19:12:27.428531Z","end":"2026-04-21T19:12:30.297022Z","steps":["trace[865457690] 'agreement among raft nodes before linearized reading' (duration: 2.868429973s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.297057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.335318Z","time spent":"961.711746ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:30.297106Z","caller":"traceutil/trace.go:171","msg":"trace[1378590716] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"425.177531ms","start":"2026-04-21T19:12:29.871917Z","end":"2026-04-21T19:12:30.297094Z","steps":["trace[1378590716] 'process raft request' (duration: 423.616474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.297058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:28.528862Z","time spent":"1.768187382s","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:12:30.297191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:29.871888Z","time spent":"425.243353ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:30.297065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:27.428512Z","time spent":"2.868546948s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T19:12:30.510002Z","caller":"traceutil/trace.go:171","msg":"trace[1221829652] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"200.330183ms","start":"2026-04-21T19:12:30.309656Z","end":"2026-04-21T19:12:30.509987Z","steps":["trace[1221829652] 'process raft request' (duration: 200.167318ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.509889Z","caller":"traceutil/trace.go:171","msg":"trace[18903117] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"200.306243ms","start":"2026-04-21T19:12:30.309558Z","end":"2026-04-21T19:12:30.509864Z","steps":["trace[18903117] 'process raft request' (duration: 200.20855ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.510216Z","caller":"traceutil/trace.go:171","msg":"trace[524257714] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"200.24743ms","start":"2026-04-21T19:12:30.309956Z","end":"2026-04-21T19:12:30.510203Z","steps":["trace[524257714] 'process raft request' (duration: 200.022212ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.510195Z","caller":"traceutil/trace.go:171","msg":"trace[1713569305] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"200.280991ms","start":"2026-04-21T19:12:30.309895Z","end":"2026-04-21T19:12:30.510175Z","steps":["trace[1713569305] 'process raft request' (duration: 200.037042ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.51035Z","caller":"traceutil/trace.go:171","msg":"trace[364241268] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"200.331423ms","start":"2026-04-21T19:12:30.309998Z","end":"2026-04-21T19:12:30.51033Z","steps":["trace[364241268] 'process raft request' (duration: 200.031572ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.510785Z","caller":"traceutil/trace.go:171","msg":"trace[2029824307] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"200.554141ms","start":"2026-04-21T19:12:30.309709Z","end":"2026-04-21T19:12:30.510263Z","steps":["trace[2029824307] 'process raft request' (duration: 200.135756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.511047Z","caller":"traceutil/trace.go:171","msg":"trace[982495683] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"193.935171ms","start":"2026-04-21T19:12:30.317065Z","end":"2026-04-21T19:12:30.511Z","steps":["trace[982495683] 'process raft request' (duration: 193.017018ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.511495Z","caller":"traceutil/trace.go:171","msg":"trace[666836574] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"182.458665ms","start":"2026-04-21T19:12:30.328995Z","end":"2026-04-21T19:12:30.511453Z","steps":["trace[666836574] 'process raft request' (duration: 181.871754ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.511196Z","caller":"traceutil/trace.go:171","msg":"trace[1923427230] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"184.217708ms","start":"2026-04-21T19:12:30.326955Z","end":"2026-04-21T19:12:30.511173Z","steps":["trace[1923427230] 'process raft request' (duration: 183.874326ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.513575Z","caller":"traceutil/trace.go:171","msg":"trace[687816172] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"154.126458ms","start":"2026-04-21T19:12:30.359431Z","end":"2026-04-21T19:12:30.513557Z","steps":["trace[687816172] 'process raft request' (duration: 153.132782ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.514039Z","caller":"traceutil/trace.go:171","msg":"trace[1335321872] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"153.592898ms","start":"2026-04-21T19:12:30.360432Z","end":"2026-04-21T19:12:30.514025Z","steps":["trace[1335321872] 'process raft request' (duration: 152.731707ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.514205Z","caller":"traceutil/trace.go:171","msg":"trace[2003127959] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"126.32534ms","start":"2026-04-21T19:12:30.387852Z","end":"2026-04-21T19:12:30.514177Z","steps":["trace[2003127959] 'process raft request' (duration: 125.723398ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.514413Z","caller":"traceutil/trace.go:171","msg":"trace[415423752] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"204.809107ms","start":"2026-04-21T19:12:30.309587Z","end":"2026-04-21T19:12:30.514397Z","steps":["trace[415423752] 'read index received' (duration: 177.452095ms)","trace[415423752] 'applied index is now lower than readState.Index' (duration: 27.356162ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:30.514491Z","caller":"traceutil/trace.go:171","msg":"trace[1558821611] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"115.095104ms","start":"2026-04-21T19:12:30.399383Z","end":"2026-04-21T19:12:30.514478Z","steps":["trace[1558821611] 'process raft request' (duration: 114.717189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.514645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.022344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:30.514712Z","caller":"traceutil/trace.go:171","msg":"trace[925952648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"205.142008ms","start":"2026-04-21T19:12:30.309553Z","end":"2026-04-21T19:12:30.514695Z","steps":["trace[925952648] 'agreement among raft nodes before linearized reading' (duration: 204.92177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:30.838626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.74248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:30.838745Z","caller":"traceutil/trace.go:171","msg":"trace[1082706420] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"277.608445ms","start":"2026-04-21T19:12:30.561117Z","end":"2026-04-21T19:12:30.838725Z","steps":["trace[1082706420] 'process raft request' (duration: 146.662998ms)","trace[1082706420] 'compare' (duration: 130.369526ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:30.844377Z","caller":"traceutil/trace.go:171","msg":"trace[1724378051] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"282.611906ms","start":"2026-04-21T19:12:30.561738Z","end":"2026-04-21T19:12:30.844349Z","steps":["trace[1724378051] 'process raft request' (duration: 282.354877ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.844671Z","caller":"traceutil/trace.go:171","msg":"trace[1207878394] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"269.533082ms","start":"2026-04-21T19:12:30.57509Z","end":"2026-04-21T19:12:30.844623Z","steps":["trace[1207878394] 'process raft request' (duration: 269.20469ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:30.8494Z","caller":"traceutil/trace.go:171","msg":"trace[1118030509] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"113.725894ms","start":"2026-04-21T19:12:30.735646Z","end":"2026-04-21T19:12:30.849372Z","steps":["trace[1118030509] 'process raft request' (duration: 113.577068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:31.35899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.39388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:31.359223Z","caller":"traceutil/trace.go:171","msg":"trace[133232193] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"462.286186ms","start":"2026-04-21T19:12:30.896916Z","end":"2026-04-21T19:12:31.359202Z","steps":["trace[133232193] 'process raft request' (duration: 90.585604ms)","trace[133232193] 'compare' (duration: 371.193154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:31.359295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:30.896894Z","time spent":"462.371108ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:31.359633Z","caller":"traceutil/trace.go:171","msg":"trace[770545778] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"424.230535ms","start":"2026-04-21T19:12:30.935392Z","end":"2026-04-21T19:12:31.359622Z","steps":["trace[770545778] 'process raft request' (duration: 424.040469ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:31.359655Z","caller":"traceutil/trace.go:171","msg":"trace[517675301] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"438.652867ms","start":"2026-04-21T19:12:30.92097Z","end":"2026-04-21T19:12:31.359623Z","steps":["trace[517675301] 'process raft request' (duration: 438.145119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:31.359693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:30.935362Z","time spent":"424.303698ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:31.359819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:30.920947Z","time spent":"438.775742ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:31.765121Z","caller":"traceutil/trace.go:171","msg":"trace[583827244] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"198.535477ms","start":"2026-04-21T19:12:31.566556Z","end":"2026-04-21T19:12:31.765092Z","steps":["trace[583827244] 'process raft request' (duration: 116.171685ms)","trace[583827244] 'compare' (duration: 82.210097ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:31.765885Z","caller":"traceutil/trace.go:171","msg":"trace[487145117] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"154.924814ms","start":"2026-04-21T19:12:31.610946Z","end":"2026-04-21T19:12:31.765871Z","steps":["trace[487145117] 'process raft request' (duration: 154.83045ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:31.980105Z","caller":"traceutil/trace.go:171","msg":"trace[1827689859] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"185.80331ms","start":"2026-04-21T19:12:31.794274Z","end":"2026-04-21T19:12:31.980077Z","steps":["trace[1827689859] 'process raft request' (duration: 174.749434ms)","trace[1827689859] 'compare' (duration: 10.910821ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:31.980725Z","caller":"traceutil/trace.go:171","msg":"trace[2001012998] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"185.202158ms","start":"2026-04-21T19:12:31.795492Z","end":"2026-04-21T19:12:31.980695Z","steps":["trace[2001012998] 'process raft request' (duration: 185.052253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.293314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.418688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:32.293454Z","caller":"traceutil/trace.go:171","msg":"trace[1828933409] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"233.651951ms","start":"2026-04-21T19:12:32.059781Z","end":"2026-04-21T19:12:32.293433Z","steps":["trace[1828933409] 'process raft request' (duration: 22.965893ms)","trace[1828933409] 'compare' (duration: 210.113937ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:32.294059Z","caller":"traceutil/trace.go:171","msg":"trace[466471107] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"137.96896ms","start":"2026-04-21T19:12:32.15605Z","end":"2026-04-21T19:12:32.294019Z","steps":["trace[466471107] 'process raft request' (duration: 137.598157ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.45937Z","caller":"traceutil/trace.go:171","msg":"trace[204922327] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"135.234202ms","start":"2026-04-21T19:12:32.324102Z","end":"2026-04-21T19:12:32.459336Z","steps":["trace[204922327] 'read index received' (duration: 55.434843ms)","trace[204922327] 'applied index is now lower than readState.Index' (duration: 79.797789ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:32.459536Z","caller":"traceutil/trace.go:171","msg":"trace[396780522] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"142.968677ms","start":"2026-04-21T19:12:32.316543Z","end":"2026-04-21T19:12:32.459512Z","steps":["trace[396780522] 'process raft request' (duration: 142.574904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.459661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.371849ms","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-21T19:12:32.459727Z","caller":"traceutil/trace.go:171","msg":"trace[1121396255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"126.454411ms","start":"2026-04-21T19:12:32.333257Z","end":"2026-04-21T19:12:32.459711Z","steps":["trace[1121396255] 'agreement among raft nodes before linearized reading' (duration: 126.332747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.459768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.605547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-21T19:12:32.459855Z","caller":"traceutil/trace.go:171","msg":"trace[1496773561] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:570; }","duration":"135.758092ms","start":"2026-04-21T19:12:32.324081Z","end":"2026-04-21T19:12:32.459839Z","steps":["trace[1496773561] 'agreement among raft nodes before linearized reading' (duration: 135.446021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.460184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.870797ms","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-21T19:12:32.460218Z","caller":"traceutil/trace.go:171","msg":"trace[1109278528] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"126.907998ms","start":"2026-04-21T19:12:32.333302Z","end":"2026-04-21T19:12:32.46021Z","steps":["trace[1109278528] 'agreement among raft nodes before linearized reading' (duration: 126.860696ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.654146Z","caller":"traceutil/trace.go:171","msg":"trace[893946956] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"181.187351ms","start":"2026-04-21T19:12:32.47293Z","end":"2026-04-21T19:12:32.654117Z","steps":["trace[893946956] 'process raft request' (duration: 87.956797ms)","trace[893946956] 'compare' (duration: 92.984415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:32.654375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.637765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-21T19:12:32.654464Z","caller":"traceutil/trace.go:171","msg":"trace[1903806360] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:571; }","duration":"173.773929ms","start":"2026-04-21T19:12:32.480664Z","end":"2026-04-21T19:12:32.654438Z","steps":["trace[1903806360] 'agreement among raft nodes before linearized reading' (duration: 173.52158ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.654466Z","caller":"traceutil/trace.go:171","msg":"trace[1060861778] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"173.331873ms","start":"2026-04-21T19:12:32.480708Z","end":"2026-04-21T19:12:32.65404Z","steps":["trace[1060861778] 'read index received' (duration: 80.099229ms)","trace[1060861778] 'applied index is now lower than readState.Index' (duration: 93.231254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:32.654754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.226186ms","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-21T19:12:32.654801Z","caller":"traceutil/trace.go:171","msg":"trace[1333526716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"141.32571ms","start":"2026-04-21T19:12:32.51346Z","end":"2026-04-21T19:12:32.654785Z","steps":["trace[1333526716] 'agreement among raft nodes before linearized reading' (duration: 141.224096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.654914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.37563ms","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-21T19:12:32.654968Z","caller":"traceutil/trace.go:171","msg":"trace[169007765] 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:571; }","duration":"115.448772ms","start":"2026-04-21T19:12:32.539505Z","end":"2026-04-21T19:12:32.654953Z","steps":["trace[169007765] 'agreement among raft nodes before linearized reading' (duration: 115.364929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.655005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.372111ms","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-21T19:12:32.655011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.458274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:32.655071Z","caller":"traceutil/trace.go:171","msg":"trace[2115845321] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"141.500506ms","start":"2026-04-21T19:12:32.513542Z","end":"2026-04-21T19:12:32.655043Z","steps":["trace[2115845321] 'agreement among raft nodes before linearized reading' (duration: 141.415843ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.655049Z","caller":"traceutil/trace.go:171","msg":"trace[993675817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"141.446813ms","start":"2026-04-21T19:12:32.513592Z","end":"2026-04-21T19:12:32.655038Z","steps":["trace[993675817] 'agreement among raft nodes before linearized reading' (duration: 141.293708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.655136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.06332ms","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-21T19:12:32.655159Z","caller":"traceutil/trace.go:171","msg":"trace[1508765455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"139.09718ms","start":"2026-04-21T19:12:32.516056Z","end":"2026-04-21T19:12:32.655153Z","steps":["trace[1508765455] 'agreement among raft nodes before linearized reading' (duration: 139.032608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.655197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.574071ms","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-21T19:12:32.655243Z","caller":"traceutil/trace.go:171","msg":"trace[747611470] 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:571; }","duration":"114.644023ms","start":"2026-04-21T19:12:32.540587Z","end":"2026-04-21T19:12:32.655231Z","steps":["trace[747611470] 'agreement among raft nodes before linearized reading' (duration: 114.574291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.654908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.14192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:32.655409Z","caller":"traceutil/trace.go:171","msg":"trace[1784950054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"122.641547ms","start":"2026-04-21T19:12:32.532724Z","end":"2026-04-21T19:12:32.655366Z","steps":["trace[1784950054] 'agreement among raft nodes before linearized reading' (duration: 122.120589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.655519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.009252ms","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-21T19:12:32.655547Z","caller":"traceutil/trace.go:171","msg":"trace[1809071202] 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:571; }","duration":"116.087125ms","start":"2026-04-21T19:12:32.539451Z","end":"2026-04-21T19:12:32.655538Z","steps":["trace[1809071202] 'agreement among raft nodes before linearized reading' (duration: 116.027323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.781892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.556866ms","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-21T19:12:32.781975Z","caller":"traceutil/trace.go:171","msg":"trace[1629421483] 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:575; }","duration":"104.67345ms","start":"2026-04-21T19:12:32.677283Z","end":"2026-04-21T19:12:32.781956Z","steps":["trace[1629421483] 'agreement among raft nodes before linearized reading' (duration: 104.506874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.782049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462238ms","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":"info","ts":"2026-04-21T19:12:32.782208Z","caller":"traceutil/trace.go:171","msg":"trace[1598757060] 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:575; }","duration":"105.699597ms","start":"2026-04-21T19:12:32.676486Z","end":"2026-04-21T19:12:32.782186Z","steps":["trace[1598757060] 'agreement among raft nodes before linearized reading' (duration: 105.413747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.782088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.49712ms","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":"info","ts":"2026-04-21T19:12:32.782353Z","caller":"traceutil/trace.go:171","msg":"trace[669265434] 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:575; }","duration":"105.81186ms","start":"2026-04-21T19:12:32.676522Z","end":"2026-04-21T19:12:32.782334Z","steps":["trace[669265434] 'agreement among raft nodes before linearized reading' (duration: 105.258331ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.839308Z","caller":"traceutil/trace.go:171","msg":"trace[300922784] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"119.535935ms","start":"2026-04-21T19:12:32.719751Z","end":"2026-04-21T19:12:32.839287Z","steps":["trace[300922784] 'process raft request' (duration: 119.39516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:32.839643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.981792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-21T19:12:32.839704Z","caller":"traceutil/trace.go:171","msg":"trace[860266500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:576; }","duration":"120.084385ms","start":"2026-04-21T19:12:32.719603Z","end":"2026-04-21T19:12:32.839687Z","steps":["trace[860266500] 'agreement among raft nodes before linearized reading' (duration: 119.776004ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.91645Z","caller":"traceutil/trace.go:171","msg":"trace[118341296] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"111.120649ms","start":"2026-04-21T19:12:32.805301Z","end":"2026-04-21T19:12:32.916422Z","steps":["trace[118341296] 'process raft request' (duration: 110.894961ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:32.956658Z","caller":"traceutil/trace.go:171","msg":"trace[1880958551] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"108.89426ms","start":"2026-04-21T19:12:32.847743Z","end":"2026-04-21T19:12:32.956637Z","steps":["trace[1880958551] 'process raft request' (duration: 108.743714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:33.139468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.855568ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T19:12:33.13972Z","caller":"traceutil/trace.go:171","msg":"trace[1320310826] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"249.906284ms","start":"2026-04-21T19:12:32.889781Z","end":"2026-04-21T19:12:33.139687Z","steps":["trace[1320310826] 'read index received' (duration: 26.395656ms)","trace[1320310826] 'applied index is now lower than readState.Index' (duration: 223.504738ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:33.139777Z","caller":"traceutil/trace.go:171","msg":"trace[2015032474] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"198.630707ms","start":"2026-04-21T19:12:32.941129Z","end":"2026-04-21T19:12:33.13976Z","steps":["trace[2015032474] 'process raft request' (duration: 198.465751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:33.142876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.034593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-21T19:12:33.142822Z","caller":"traceutil/trace.go:171","msg":"trace[1207255101] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"156.798222ms","start":"2026-04-21T19:12:32.985997Z","end":"2026-04-21T19:12:33.142795Z","steps":["trace[1207255101] 'process raft request' (duration: 156.296964ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:33.143059Z","caller":"traceutil/trace.go:171","msg":"trace[109573421] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:579; }","duration":"253.231921ms","start":"2026-04-21T19:12:32.88976Z","end":"2026-04-21T19:12:33.142992Z","steps":["trace[109573421] 'agreement among raft nodes before linearized reading' (duration: 250.016798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:33.78511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:12:34.286247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:12:34.514743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235662624s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T19:12:34.515456Z","caller":"traceutil/trace.go:171","msg":"trace[983729830] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.236485543s","start":"2026-04-21T19:12:33.278934Z","end":"2026-04-21T19:12:34.515419Z","steps":["trace[983729830] 'process raft request' (duration: 1.236228254s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.515673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:33.278914Z","time spent":"1.236639959s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:34.528532Z","caller":"traceutil/trace.go:171","msg":"trace[1149372404] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"1.243659469s","start":"2026-04-21T19:12:33.284847Z","end":"2026-04-21T19:12:34.528507Z","steps":["trace[1149372404] 'read index received' (duration: 1.230235285s)","trace[1149372404] 'applied index is now lower than readState.Index' (duration: 13.422884ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:34.528642Z","caller":"traceutil/trace.go:171","msg":"trace[1657601298] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.166885748s","start":"2026-04-21T19:12:33.361745Z","end":"2026-04-21T19:12:34.528631Z","steps":["trace[1657601298] 'process raft request' (duration: 1.166393051s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.528769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:33.3617Z","time spent":"1.166975131s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:34.529044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244196198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-04-21T19:12:34.529075Z","caller":"traceutil/trace.go:171","msg":"trace[249137158] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"1.24424976s","start":"2026-04-21T19:12:33.284817Z","end":"2026-04-21T19:12:34.529067Z","steps":["trace[249137158] 'agreement among raft nodes before linearized reading' (duration: 1.244144897s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.529096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:33.284793Z","time spent":"1.244298432s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9173,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-21T19:12:34.529229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12356022s","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-21T19:12:34.529254Z","caller":"traceutil/trace.go:171","msg":"trace[775174423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"1.123631472s","start":"2026-04-21T19:12:33.405617Z","end":"2026-04-21T19:12:34.529248Z","steps":["trace[775174423] 'agreement among raft nodes before linearized reading' (duration: 1.12356729s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.529273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:33.40559Z","time spent":"1.123676714s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T19:12:34.529407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.808803ms","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-21T19:12:34.529434Z","caller":"traceutil/trace.go:171","msg":"trace[390261220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"145.873865ms","start":"2026-04-21T19:12:34.383553Z","end":"2026-04-21T19:12:34.529427Z","steps":["trace[390261220] 'agreement among raft nodes before linearized reading' (duration: 145.810252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.529528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.227213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:34.529619Z","caller":"traceutil/trace.go:171","msg":"trace[1617378257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"999.366997ms","start":"2026-04-21T19:12:33.53023Z","end":"2026-04-21T19:12:34.529597Z","steps":["trace[1617378257] 'agreement among raft nodes before linearized reading' (duration: 999.226933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:34.529668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:33.530209Z","time spent":"999.450551ms","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T19:12:35.033423Z","caller":"traceutil/trace.go:171","msg":"trace[1450381802] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"341.39068ms","start":"2026-04-21T19:12:34.692004Z","end":"2026-04-21T19:12:35.033394Z","steps":["trace[1450381802] 'read index received' (duration: 265.595544ms)","trace[1450381802] 'applied index is now lower than readState.Index' (duration: 75.793966ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:35.033486Z","caller":"traceutil/trace.go:171","msg":"trace[546280225] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"346.606271ms","start":"2026-04-21T19:12:34.686843Z","end":"2026-04-21T19:12:35.03345Z","steps":["trace[546280225] 'process raft request' (duration: 270.599218ms)","trace[546280225] 'compare' (duration: 75.813586ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:35.033629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:34.686826Z","time spent":"346.730404ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:35.033647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.616197ms","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-21T19:12:35.033687Z","caller":"traceutil/trace.go:171","msg":"trace[442600461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"341.69377ms","start":"2026-04-21T19:12:34.691981Z","end":"2026-04-21T19:12:35.033675Z","steps":["trace[442600461] 'agreement among raft nodes before linearized reading' (duration: 341.523454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:35.033722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:34.691972Z","time spent":"341.742702ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T19:12:35.759541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.577195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:12:35.759672Z","caller":"traceutil/trace.go:171","msg":"trace[1533780525] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"722.466648ms","start":"2026-04-21T19:12:35.037193Z","end":"2026-04-21T19:12:35.759659Z","steps":["trace[1533780525] 'process raft request' (duration: 722.424257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:35.759715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:35.037176Z","time spent":"722.51936ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:35.759766Z","caller":"traceutil/trace.go:171","msg":"trace[1259749932] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"984.421723ms","start":"2026-04-21T19:12:34.775318Z","end":"2026-04-21T19:12:35.759739Z","steps":["trace[1259749932] 'process raft request' (duration: 785.586609ms)","trace[1259749932] 'compare' (duration: 198.373378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:35.759878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:34.77529Z","time spent":"984.522817ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:38.486026Z","caller":"traceutil/trace.go:171","msg":"trace[229216387] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"103.861832ms","start":"2026-04-21T19:12:38.382142Z","end":"2026-04-21T19:12:38.486004Z","steps":["trace[229216387] 'process raft request' (duration: 103.746539ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:38.492246Z","caller":"traceutil/trace.go:171","msg":"trace[1352097220] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"109.52536ms","start":"2026-04-21T19:12:38.382688Z","end":"2026-04-21T19:12:38.492214Z","steps":["trace[1352097220] 'process raft request' (duration: 109.361565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:42.238477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.436933ms","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-21T19:12:42.238575Z","caller":"traceutil/trace.go:171","msg":"trace[163939847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"452.581598ms","start":"2026-04-21T19:12:41.785973Z","end":"2026-04-21T19:12:42.238554Z","steps":["trace[163939847] 'range keys from in-memory index tree' (duration: 452.270478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:42.23862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:41.785956Z","time spent":"452.65541ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T19:12:42.347681Z","caller":"traceutil/trace.go:171","msg":"trace[2008000325] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"445.541414ms","start":"2026-04-21T19:12:41.902111Z","end":"2026-04-21T19:12:42.347652Z","steps":["trace[2008000325] 'process raft request' (duration: 442.571509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:42.347889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:41.902094Z","time spent":"445.714691ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:42.418958Z","caller":"traceutil/trace.go:171","msg":"trace[2136950151] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"176.438049ms","start":"2026-04-21T19:12:42.242499Z","end":"2026-04-21T19:12:42.418938Z","steps":["trace[2136950151] 'process raft request' (duration: 176.344076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:43.031469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195256,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T19:12:43.317813Z","caller":"traceutil/trace.go:171","msg":"trace[1545657471] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"787.029211ms","start":"2026-04-21T19:12:42.530746Z","end":"2026-04-21T19:12:43.317775Z","steps":["trace[1545657471] 'read index received' (duration: 711.661174ms)","trace[1545657471] 'applied index is now lower than readState.Index' (duration: 75.366666ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:43.317909Z","caller":"traceutil/trace.go:171","msg":"trace[952268869] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"909.228859ms","start":"2026-04-21T19:12:42.408621Z","end":"2026-04-21T19:12:43.31785Z","steps":["trace[952268869] 'process raft request' (duration: 833.77525ms)","trace[952268869] 'compare' (duration: 74.940133ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:43.318059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:42.408601Z","time spent":"909.374974ms","remote":"127.0.0.1:35274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:43.318282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.723612ms","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-21T19:12:43.318333Z","caller":"traceutil/trace.go:171","msg":"trace[797359638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"746.808433ms","start":"2026-04-21T19:12:42.571512Z","end":"2026-04-21T19:12:43.31832Z","steps":["trace[797359638] 'agreement among raft nodes before linearized reading' (duration: 746.421651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:43.318423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:42.571502Z","time spent":"746.857766ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T19:12:43.318706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.181371ms","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-21T19:12:43.318754Z","caller":"traceutil/trace.go:171","msg":"trace[1012122517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"233.262844ms","start":"2026-04-21T19:12:43.085478Z","end":"2026-04-21T19:12:43.318741Z","steps":["trace[1012122517] 'agreement among raft nodes before linearized reading' (duration: 233.112739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:43.318786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.826287ms","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-21T19:12:43.318865Z","caller":"traceutil/trace.go:171","msg":"trace[740104188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"742.966742ms","start":"2026-04-21T19:12:42.575877Z","end":"2026-04-21T19:12:43.318843Z","steps":["trace[740104188] 'agreement among raft nodes before linearized reading' (duration: 742.728354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:43.31884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.080923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:43.319106Z","caller":"traceutil/trace.go:171","msg":"trace[834294344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"788.252248ms","start":"2026-04-21T19:12:42.53073Z","end":"2026-04-21T19:12:43.318982Z","steps":["trace[834294344] 'agreement among raft nodes before linearized reading' (duration: 787.180555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:43.318917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:42.575856Z","time spent":"743.052054ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T19:12:43.319203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:42.530674Z","time spent":"788.483356ms","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:12:44.922891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:12:45.423274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:12:45.924223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:12:46.261498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.450161676s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T19:12:46.263443Z","caller":"traceutil/trace.go:171","msg":"trace[176045155] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"2.453576541s","start":"2026-04-21T19:12:43.80983Z","end":"2026-04-21T19:12:46.263406Z","steps":["trace[176045155] 'process raft request' (duration: 2.452686804s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:46.263678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:43.809802Z","time spent":"2.453747786s","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:12:46.28961Z","caller":"traceutil/trace.go:171","msg":"trace[1811892712] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"1.867533666s","start":"2026-04-21T19:12:44.422006Z","end":"2026-04-21T19:12:46.28954Z","steps":["trace[1811892712] 'read index received' (duration: 1.839859454s)","trace[1811892712] 'applied index is now lower than readState.Index' (duration: 27.672252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:46.289857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867823984s","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-21T19:12:46.289895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759403286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:46.289946Z","caller":"traceutil/trace.go:171","msg":"trace[357866354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.86799019s","start":"2026-04-21T19:12:44.421936Z","end":"2026-04-21T19:12:46.289926Z","steps":["trace[357866354] 'agreement among raft nodes before linearized reading' (duration: 1.867692711s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:46.289967Z","caller":"traceutil/trace.go:171","msg":"trace[32709276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.759555361s","start":"2026-04-21T19:12:44.530399Z","end":"2026-04-21T19:12:46.289954Z","steps":["trace[32709276] 'agreement among raft nodes before linearized reading' (duration: 1.759389376s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:46.290001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:44.421904Z","time spent":"1.868088033s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T19:12:46.290046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:44.530387Z","time spent":"1.759647193s","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:12:46.290359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.815079ms","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-21T19:12:46.290399Z","caller":"traceutil/trace.go:171","msg":"trace[1822984932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"921.893501ms","start":"2026-04-21T19:12:45.368495Z","end":"2026-04-21T19:12:46.290389Z","steps":["trace[1822984932] 'agreement among raft nodes before linearized reading' (duration: 921.800088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:46.290431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:45.368447Z","time spent":"921.977914ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T19:12:46.290566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.048633ms","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-21T19:12:46.29064Z","caller":"traceutil/trace.go:171","msg":"trace[1430508133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"945.193457ms","start":"2026-04-21T19:12:45.345433Z","end":"2026-04-21T19:12:46.290627Z","steps":["trace[1430508133] 'agreement among raft nodes before linearized reading' (duration: 944.946989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:46.29068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:45.345363Z","time spent":"945.30865ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T19:12:46.290605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.938122ms","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-21T19:12:46.290849Z","caller":"traceutil/trace.go:171","msg":"trace[1051466704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"921.20357ms","start":"2026-04-21T19:12:45.369635Z","end":"2026-04-21T19:12:46.290839Z","steps":["trace[1051466704] 'agreement among raft nodes before linearized reading' (duration: 920.913831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:46.290892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:45.369623Z","time spent":"921.254771ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T19:12:48.888855Z","caller":"traceutil/trace.go:171","msg":"trace[1253977995] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"178.318751ms","start":"2026-04-21T19:12:48.710509Z","end":"2026-04-21T19:12:48.888828Z","steps":["trace[1253977995] 'process raft request' (duration: 178.199816ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:49.892672Z","caller":"traceutil/trace.go:171","msg":"trace[1979797714] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"193.020528ms","start":"2026-04-21T19:12:49.699612Z","end":"2026-04-21T19:12:49.892632Z","steps":["trace[1979797714] 'process raft request' (duration: 95.36639ms)","trace[1979797714] 'compare' (duration: 97.449812ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:49.956675Z","caller":"traceutil/trace.go:171","msg":"trace[1634338685] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"254.534881ms","start":"2026-04-21T19:12:49.702096Z","end":"2026-04-21T19:12:49.956631Z","steps":["trace[1634338685] 'process raft request' (duration: 254.316405ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:49.956711Z","caller":"traceutil/trace.go:171","msg":"trace[1729039074] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"244.081793ms","start":"2026-04-21T19:12:49.712572Z","end":"2026-04-21T19:12:49.956653Z","steps":["trace[1729039074] 'process raft request' (duration: 244.028551ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:49.956784Z","caller":"traceutil/trace.go:171","msg":"trace[1570866169] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"252.367657ms","start":"2026-04-21T19:12:49.704354Z","end":"2026-04-21T19:12:49.956722Z","steps":["trace[1570866169] 'process raft request' (duration: 252.183421ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:50.243602Z","caller":"traceutil/trace.go:171","msg":"trace[1932701636] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"342.378096ms","start":"2026-04-21T19:12:49.901202Z","end":"2026-04-21T19:12:50.24358Z","steps":["trace[1932701636] 'process raft request' (duration: 342.313164ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:50.243662Z","caller":"traceutil/trace.go:171","msg":"trace[1907127180] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"427.759882ms","start":"2026-04-21T19:12:49.815854Z","end":"2026-04-21T19:12:50.243614Z","steps":["trace[1907127180] 'process raft request' (duration: 427.538936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:50.243861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:49.901176Z","time spent":"342.578381ms","remote":"127.0.0.1:35236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:12:50.243971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:49.815828Z","time spent":"428.078312ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:50.403312Z","caller":"traceutil/trace.go:171","msg":"trace[965075144] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"409.419342ms","start":"2026-04-21T19:12:49.993873Z","end":"2026-04-21T19:12:50.403292Z","steps":["trace[965075144] 'process raft request' (duration: 409.111113ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:50.403406Z","caller":"traceutil/trace.go:171","msg":"trace[431289233] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"387.876033ms","start":"2026-04-21T19:12:50.015505Z","end":"2026-04-21T19:12:50.403381Z","steps":["trace[431289233] 'process raft request' (duration: 387.644846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:50.4035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:50.015487Z","time spent":"387.966405ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:50.403716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:49.993855Z","time spent":"409.554366ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:12:50.403872Z","caller":"traceutil/trace.go:171","msg":"trace[626365395] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:707; }","duration":"189.122205ms","start":"2026-04-21T19:12:50.214106Z","end":"2026-04-21T19:12:50.403228Z","steps":["trace[626365395] 'read index received' (duration: 29.397747ms)","trace[626365395] 'applied index is now lower than readState.Index' (duration: 159.723078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:50.4041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.979971ms","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-21T19:12:50.405066Z","caller":"traceutil/trace.go:171","msg":"trace[29638062] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:689; }","duration":"190.98006ms","start":"2026-04-21T19:12:50.21405Z","end":"2026-04-21T19:12:50.40503Z","steps":["trace[29638062] 'agreement among raft nodes before linearized reading' (duration: 189.95342ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:50.436387Z","caller":"traceutil/trace.go:171","msg":"trace[666980965] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"187.139978ms","start":"2026-04-21T19:12:50.249228Z","end":"2026-04-21T19:12:50.436368Z","steps":["trace[666980965] 'process raft request' (duration: 187.048625ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:50.682998Z","caller":"traceutil/trace.go:171","msg":"trace[2043108493] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"146.724369ms","start":"2026-04-21T19:12:50.536247Z","end":"2026-04-21T19:12:50.682971Z","steps":["trace[2043108493] 'process raft request' (duration: 119.160065ms)","trace[2043108493] 'compare' (duration: 27.453631ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:50.732007Z","caller":"traceutil/trace.go:171","msg":"trace[1691207518] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"114.57818ms","start":"2026-04-21T19:12:50.617403Z","end":"2026-04-21T19:12:50.731981Z","steps":["trace[1691207518] 'process raft request' (duration: 114.441686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:51.204007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.644153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:12:51.204102Z","caller":"traceutil/trace.go:171","msg":"trace[1788352252] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:709; }","duration":"190.808359ms","start":"2026-04-21T19:12:51.013271Z","end":"2026-04-21T19:12:51.20408Z","steps":["trace[1788352252] 'range keys from in-memory index tree' (duration: 190.525641ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.233412Z","caller":"traceutil/trace.go:171","msg":"trace[1337736152] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"260.162367ms","start":"2026-04-21T19:12:55.973214Z","end":"2026-04-21T19:12:56.233376Z","steps":["trace[1337736152] 'process raft request' (duration: 260.023334ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.278645Z","caller":"traceutil/trace.go:171","msg":"trace[2019830099] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"274.899513ms","start":"2026-04-21T19:12:56.00373Z","end":"2026-04-21T19:12:56.278629Z","steps":["trace[2019830099] 'read index received' (duration: 229.480807ms)","trace[2019830099] 'applied index is now lower than readState.Index' (duration: 45.417876ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:56.278732Z","caller":"traceutil/trace.go:171","msg":"trace[1522832795] transaction","detail":"{read_only:false; number_of_response:0; response_revision:746; }","duration":"304.730599ms","start":"2026-04-21T19:12:55.973991Z","end":"2026-04-21T19:12:56.278722Z","steps":["trace[1522832795] 'process raft request' (duration: 304.593576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:56.278812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:12:55.973979Z","time spent":"304.77564ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:12:56.27898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.216333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-d9jmh.18a8751231adae61\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-04-21T19:12:56.27906Z","caller":"traceutil/trace.go:171","msg":"trace[1450148279] range","detail":"{range_begin:/registry/events/kube-system/cilium-d9jmh.18a8751231adae61; range_end:; response_count:1; response_revision:746; }","duration":"275.352857ms","start":"2026-04-21T19:12:56.003688Z","end":"2026-04-21T19:12:56.279041Z","steps":["trace[1450148279] 'agreement among raft nodes before linearized reading' (duration: 275.074018ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.459655Z","caller":"traceutil/trace.go:171","msg":"trace[2100586199] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"180.917394ms","start":"2026-04-21T19:12:56.278696Z","end":"2026-04-21T19:12:56.459614Z","steps":["trace[2100586199] 'read index received' (duration: 180.525773ms)","trace[2100586199] 'applied index is now lower than readState.Index' (duration: 389.941µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:12:56.45989Z","caller":"traceutil/trace.go:171","msg":"trace[330249238] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"220.17108ms","start":"2026-04-21T19:12:56.239705Z","end":"2026-04-21T19:12:56.459876Z","steps":["trace[330249238] 'process raft request' (duration: 219.579274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:56.460258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.569151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1711"} {"level":"info","ts":"2026-04-21T19:12:56.460355Z","caller":"traceutil/trace.go:171","msg":"trace[1148136724] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:747; }","duration":"220.707035ms","start":"2026-04-21T19:12:56.239626Z","end":"2026-04-21T19:12:56.460333Z","steps":["trace[1148136724] 'agreement among raft nodes before linearized reading' (duration: 220.462129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:56.483384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.550338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1711"} {"level":"info","ts":"2026-04-21T19:12:56.48344Z","caller":"traceutil/trace.go:171","msg":"trace[408476466] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:747; }","duration":"202.643191ms","start":"2026-04-21T19:12:56.280784Z","end":"2026-04-21T19:12:56.483427Z","steps":["trace[408476466] 'agreement among raft nodes before linearized reading' (duration: 202.541177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:12:56.483677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.992872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T19:12:56.483861Z","caller":"traceutil/trace.go:171","msg":"trace[1529321847] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:747; }","duration":"101.212807ms","start":"2026-04-21T19:12:56.382626Z","end":"2026-04-21T19:12:56.483839Z","steps":["trace[1529321847] 'agreement among raft nodes before linearized reading' (duration: 100.697352ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.826579Z","caller":"traceutil/trace.go:171","msg":"trace[123090827] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"184.885793ms","start":"2026-04-21T19:12:56.641667Z","end":"2026-04-21T19:12:56.826553Z","steps":["trace[123090827] 'process raft request' (duration: 184.564085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.849711Z","caller":"traceutil/trace.go:171","msg":"trace[1704709226] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"207.687308ms","start":"2026-04-21T19:12:56.641995Z","end":"2026-04-21T19:12:56.849682Z","steps":["trace[1704709226] 'process raft request' (duration: 207.37655ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.849768Z","caller":"traceutil/trace.go:171","msg":"trace[1159456843] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"206.882907ms","start":"2026-04-21T19:12:56.642863Z","end":"2026-04-21T19:12:56.849746Z","steps":["trace[1159456843] 'process raft request' (duration: 206.725653ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.849909Z","caller":"traceutil/trace.go:171","msg":"trace[43552098] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"207.7313ms","start":"2026-04-21T19:12:56.642163Z","end":"2026-04-21T19:12:56.849894Z","steps":["trace[43552098] 'read index received' (duration: 184.02106ms)","trace[43552098] 'applied index is now lower than readState.Index' (duration: 23.70912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:12:56.850196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.004227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T19:12:56.850241Z","caller":"traceutil/trace.go:171","msg":"trace[503188811] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:754; }","duration":"208.09075ms","start":"2026-04-21T19:12:56.642137Z","end":"2026-04-21T19:12:56.850228Z","steps":["trace[503188811] 'agreement among raft nodes before linearized reading' (duration: 207.811412ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:12:56.849675Z","caller":"traceutil/trace.go:171","msg":"trace[2045606504] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"207.404721ms","start":"2026-04-21T19:12:56.64224Z","end":"2026-04-21T19:12:56.849645Z","steps":["trace[2045606504] 'process raft request' (duration: 207.303108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:06.03171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:13:06.532257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626022234195640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:13:07.019758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.679008371s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T19:13:07.02021Z","caller":"traceutil/trace.go:171","msg":"trace[77446143] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.679525674s","start":"2026-04-21T19:13:05.340662Z","end":"2026-04-21T19:13:07.020188Z","steps":["trace[77446143] 'process raft request' (duration: 1.679260667s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.020365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:05.34065Z","time spent":"1.679628907s","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:13:07.301346Z","caller":"traceutil/trace.go:171","msg":"trace[1599103939] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"1.770217281s","start":"2026-04-21T19:13:05.531108Z","end":"2026-04-21T19:13:07.301326Z","steps":["trace[1599103939] 'read index received' (duration: 1.488905932s)","trace[1599103939] 'applied index is now lower than readState.Index' (duration: 281.310349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:13:07.301504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770371475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:13:07.301476Z","caller":"traceutil/trace.go:171","msg":"trace[2060504099] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.643918697s","start":"2026-04-21T19:13:05.657479Z","end":"2026-04-21T19:13:07.301398Z","steps":["trace[2060504099] 'process raft request' (duration: 1.643704332s)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:07.301539Z","caller":"traceutil/trace.go:171","msg":"trace[222810799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"1.770468597s","start":"2026-04-21T19:13:05.531059Z","end":"2026-04-21T19:13:07.301527Z","steps":["trace[222810799] 'agreement among raft nodes before linearized reading' (duration: 1.770380325s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.301578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:05.53104Z","time spent":"1.770529389s","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:13:07.301645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:05.657452Z","time spent":"1.644106272s","remote":"127.0.0.1:35006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:13:07.30172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.396353ms","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-21T19:13:07.301723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.240447ms","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-21T19:13:07.301752Z","caller":"traceutil/trace.go:171","msg":"trace[1355901714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"329.465835ms","start":"2026-04-21T19:13:06.972277Z","end":"2026-04-21T19:13:07.301743Z","steps":["trace[1355901714] 'agreement among raft nodes before linearized reading' (duration: 329.362332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.301772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.972256Z","time spent":"329.513075ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T19:13:07.30178Z","caller":"traceutil/trace.go:171","msg":"trace[742574130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"351.326209ms","start":"2026-04-21T19:13:06.95044Z","end":"2026-04-21T19:13:07.301767Z","steps":["trace[742574130] 'agreement among raft nodes before linearized reading' (duration: 351.151345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.301817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.950423Z","time spent":"351.38679ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T19:13:07.301848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.703867ms","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":"info","ts":"2026-04-21T19:13:07.301942Z","caller":"traceutil/trace.go:171","msg":"trace[854794069] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:810; }","duration":"779.82008ms","start":"2026-04-21T19:13:06.522101Z","end":"2026-04-21T19:13:07.301921Z","steps":["trace[854794069] 'agreement among raft nodes before linearized reading' (duration: 779.617435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.301993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.522049Z","time spent":"779.934852ms","remote":"127.0.0.1:35132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T19:13:07.301963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.690715ms","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-21T19:13:07.30213Z","caller":"traceutil/trace.go:171","msg":"trace[1994015237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"432.861029ms","start":"2026-04-21T19:13:06.869197Z","end":"2026-04-21T19:13:07.302058Z","steps":["trace[1994015237] 'agreement among raft nodes before linearized reading' (duration: 432.421239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.302216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.869174Z","time spent":"433.029714ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T19:13:07.302222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.788257ms","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-21T19:13:07.302385Z","caller":"traceutil/trace.go:171","msg":"trace[1929267278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"335.000913ms","start":"2026-04-21T19:13:06.967363Z","end":"2026-04-21T19:13:07.302364Z","steps":["trace[1929267278] 'agreement among raft nodes before linearized reading' (duration: 334.433238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.302539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.96734Z","time spent":"335.185958ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T19:13:07.302793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.036632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T19:13:07.302856Z","caller":"traceutil/trace.go:171","msg":"trace[694677645] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:810; }","duration":"920.121323ms","start":"2026-04-21T19:13:06.382717Z","end":"2026-04-21T19:13:07.302838Z","steps":["trace[694677645] 'agreement among raft nodes before linearized reading' (duration: 918.821491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:07.303005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:13:06.3827Z","time spent":"920.289058ms","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-21T19:13:18.214746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.348824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:13:18.214942Z","caller":"traceutil/trace.go:171","msg":"trace[1309717219] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"127.117129ms","start":"2026-04-21T19:13:18.087746Z","end":"2026-04-21T19:13:18.214863Z","steps":["trace[1309717219] 'process raft request' (duration: 25.566601ms)","trace[1309717219] 'compare' (duration: 101.18347ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:13:18.235004Z","caller":"traceutil/trace.go:171","msg":"trace[1545776545] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"142.799591ms","start":"2026-04-21T19:13:18.092183Z","end":"2026-04-21T19:13:18.234983Z","steps":["trace[1545776545] 'process raft request' (duration: 142.626387ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:31.209805Z","caller":"traceutil/trace.go:171","msg":"trace[1296262949] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"125.657191ms","start":"2026-04-21T19:13:31.084121Z","end":"2026-04-21T19:13:31.209778Z","steps":["trace[1296262949] 'process raft request' (duration: 125.442737ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:31.326018Z","caller":"traceutil/trace.go:171","msg":"trace[579705822] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1045; }","duration":"133.849055ms","start":"2026-04-21T19:13:31.192143Z","end":"2026-04-21T19:13:31.325992Z","steps":["trace[579705822] 'read index received' (duration: 17.322669ms)","trace[579705822] 'applied index is now lower than readState.Index' (duration: 116.524996ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:13:31.326137Z","caller":"traceutil/trace.go:171","msg":"trace[1013379189] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"194.054973ms","start":"2026-04-21T19:13:31.132051Z","end":"2026-04-21T19:13:31.326106Z","steps":["trace[1013379189] 'process raft request' (duration: 161.117162ms)","trace[1013379189] 'compare' (duration: 32.695866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:13:31.326193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.046749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:13:31.326271Z","caller":"traceutil/trace.go:171","msg":"trace[844527046] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1015; }","duration":"134.175171ms","start":"2026-04-21T19:13:31.192078Z","end":"2026-04-21T19:13:31.326253Z","steps":["trace[844527046] 'agreement among raft nodes before linearized reading' (duration: 134.057169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:31.815335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.930508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:13:31.816047Z","caller":"traceutil/trace.go:171","msg":"trace[414296695] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"201.045933ms","start":"2026-04-21T19:13:31.614974Z","end":"2026-04-21T19:13:31.81602Z","steps":["trace[414296695] 'process raft request' (duration: 53.307829ms)","trace[414296695] 'compare' (duration: 146.792535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:13:31.816381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.194254ms","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-21T19:13:31.81645Z","caller":"traceutil/trace.go:171","msg":"trace[1134964595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"199.298097ms","start":"2026-04-21T19:13:31.617133Z","end":"2026-04-21T19:13:31.816431Z","steps":["trace[1134964595] 'agreement among raft nodes before linearized reading' (duration: 198.994891ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:31.816076Z","caller":"traceutil/trace.go:171","msg":"trace[1851427625] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"198.866798ms","start":"2026-04-21T19:13:31.617185Z","end":"2026-04-21T19:13:31.816052Z","steps":["trace[1851427625] 'read index received' (duration: 51.109135ms)","trace[1851427625] 'applied index is now lower than readState.Index' (duration: 147.754343ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:13:31.865792Z","caller":"traceutil/trace.go:171","msg":"trace[459164953] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"246.920173ms","start":"2026-04-21T19:13:31.618842Z","end":"2026-04-21T19:13:31.865762Z","steps":["trace[459164953] 'process raft request' (duration: 246.74735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:31.866014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.394294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T19:13:31.866107Z","caller":"traceutil/trace.go:171","msg":"trace[442101433] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1020; }","duration":"108.530147ms","start":"2026-04-21T19:13:31.757533Z","end":"2026-04-21T19:13:31.866063Z","steps":["trace[442101433] 'agreement among raft nodes before linearized reading' (duration: 108.375374ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:50.078389Z","caller":"traceutil/trace.go:171","msg":"trace[1183655816] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"106.241799ms","start":"2026-04-21T19:13:49.972122Z","end":"2026-04-21T19:13:50.078364Z","steps":["trace[1183655816] 'read index received' (duration: 106.106907ms)","trace[1183655816] 'applied index is now lower than readState.Index' (duration: 133.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:13:50.078435Z","caller":"traceutil/trace.go:171","msg":"trace[319734227] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"109.590758ms","start":"2026-04-21T19:13:49.968814Z","end":"2026-04-21T19:13:50.078405Z","steps":["trace[319734227] 'process raft request' (duration: 109.419095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:13:50.078658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.510823ms","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-21T19:13:50.078702Z","caller":"traceutil/trace.go:171","msg":"trace[1439286246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"106.588925ms","start":"2026-04-21T19:13:49.972101Z","end":"2026-04-21T19:13:50.078689Z","steps":["trace[1439286246] 'agreement among raft nodes before linearized reading' (duration: 106.363692ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:13:56.280696Z","caller":"traceutil/trace.go:171","msg":"trace[79005319] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"128.957705ms","start":"2026-04-21T19:13:56.151702Z","end":"2026-04-21T19:13:56.28066Z","steps":["trace[79005319] 'process raft request' (duration: 117.506593ms)","trace[79005319] 'compare' (duration: 11.090576ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:14:28.102966Z","caller":"traceutil/trace.go:171","msg":"trace[1486894197] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"179.245524ms","start":"2026-04-21T19:14:27.923694Z","end":"2026-04-21T19:14:28.102939Z","steps":["trace[1486894197] 'process raft request' (duration: 171.488325ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:36.092093Z","caller":"traceutil/trace.go:171","msg":"trace[1157277291] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"177.721657ms","start":"2026-04-21T19:14:35.914165Z","end":"2026-04-21T19:14:36.091887Z","steps":["trace[1157277291] 'process raft request' (duration: 177.575805ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:40.845203Z","caller":"traceutil/trace.go:171","msg":"trace[593301388] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"107.681963ms","start":"2026-04-21T19:14:40.7375Z","end":"2026-04-21T19:14:40.845182Z","steps":["trace[593301388] 'process raft request' (duration: 107.541881ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:44.350884Z","caller":"traceutil/trace.go:171","msg":"trace[1229089278] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"143.577145ms","start":"2026-04-21T19:14:44.207269Z","end":"2026-04-21T19:14:44.350846Z","steps":["trace[1229089278] 'process raft request' (duration: 84.619987ms)","trace[1229089278] 'compare' (duration: 58.716235ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:14:44.584879Z","caller":"traceutil/trace.go:171","msg":"trace[602000326] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"226.228224ms","start":"2026-04-21T19:14:44.358627Z","end":"2026-04-21T19:14:44.584856Z","steps":["trace[602000326] 'process raft request' (duration: 226.127883ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:44.595755Z","caller":"traceutil/trace.go:171","msg":"trace[282571595] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"165.94797ms","start":"2026-04-21T19:14:44.429776Z","end":"2026-04-21T19:14:44.595724Z","steps":["trace[282571595] 'read index received' (duration: 155.158257ms)","trace[282571595] 'applied index is now lower than readState.Index' (duration: 10.788223ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:14:44.595875Z","caller":"traceutil/trace.go:171","msg":"trace[1913001337] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"185.149803ms","start":"2026-04-21T19:14:44.4107Z","end":"2026-04-21T19:14:44.59585Z","steps":["trace[1913001337] 'process raft request' (duration: 184.89339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:44.596069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.270374ms","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-21T19:14:44.596132Z","caller":"traceutil/trace.go:171","msg":"trace[945827933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"166.419596ms","start":"2026-04-21T19:14:44.429698Z","end":"2026-04-21T19:14:44.596118Z","steps":["trace[945827933] 'agreement among raft nodes before linearized reading' (duration: 166.183763ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:44.783091Z","caller":"traceutil/trace.go:171","msg":"trace[1761797286] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"149.793516ms","start":"2026-04-21T19:14:44.633274Z","end":"2026-04-21T19:14:44.783068Z","steps":["trace[1761797286] 'process raft request' (duration: 149.657655ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:52.949498Z","caller":"traceutil/trace.go:171","msg":"trace[1543500867] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"329.2202ms","start":"2026-04-21T19:14:52.620246Z","end":"2026-04-21T19:14:52.949466Z","steps":["trace[1543500867] 'process raft request' (duration: 329.039428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:52.949708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:52.620227Z","time spent":"329.374983ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:14:53.453635Z","caller":"traceutil/trace.go:171","msg":"trace[885762018] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"796.163918ms","start":"2026-04-21T19:14:52.657432Z","end":"2026-04-21T19:14:53.453596Z","steps":["trace[885762018] 'read index received' (duration: 291.864822ms)","trace[885762018] 'applied index is now lower than readState.Index' (duration: 504.297366ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:14:53.453759Z","caller":"traceutil/trace.go:171","msg":"trace[1702770866] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"815.208762ms","start":"2026-04-21T19:14:52.638522Z","end":"2026-04-21T19:14:53.45373Z","steps":["trace[1702770866] 'process raft request' (duration: 778.481553ms)","trace[1702770866] 'compare' (duration: 36.327454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:14:53.453876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.426851ms","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-21T19:14:53.453899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:52.638506Z","time spent":"815.318214ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:14:53.453913Z","caller":"traceutil/trace.go:171","msg":"trace[807748314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"796.528813ms","start":"2026-04-21T19:14:52.657373Z","end":"2026-04-21T19:14:53.453902Z","steps":["trace[807748314] 'agreement among raft nodes before linearized reading' (duration: 796.35766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:53.45395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:52.657358Z","time spent":"796.584333ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T19:14:53.528147Z","caller":"traceutil/trace.go:171","msg":"trace[992636807] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"239.629301ms","start":"2026-04-21T19:14:53.288498Z","end":"2026-04-21T19:14:53.528127Z","steps":["trace[992636807] 'process raft request' (duration: 239.330268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:53.528401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.7521ms","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-21T19:14:53.528538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.207881ms","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-21T19:14:53.528583Z","caller":"traceutil/trace.go:171","msg":"trace[1574232389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"557.527175ms","start":"2026-04-21T19:14:52.971045Z","end":"2026-04-21T19:14:53.528572Z","steps":["trace[1574232389] 'agreement among raft nodes before linearized reading' (duration: 557.354393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:53.528614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:52.971025Z","time spent":"557.582005ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T19:14:53.528594Z","caller":"traceutil/trace.go:171","msg":"trace[600168664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"715.028423ms","start":"2026-04-21T19:14:52.813494Z","end":"2026-04-21T19:14:53.528522Z","steps":["trace[600168664] 'agreement among raft nodes before linearized reading' (duration: 714.502047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:53.528713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:52.813472Z","time spent":"715.223446ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T19:14:53.568725Z","caller":"traceutil/trace.go:171","msg":"trace[1235744888] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"111.300641ms","start":"2026-04-21T19:14:53.4574Z","end":"2026-04-21T19:14:53.5687Z","steps":["trace[1235744888] 'process raft request' (duration: 109.678811ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:59.786972Z","caller":"traceutil/trace.go:171","msg":"trace[1234085201] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"159.458468ms","start":"2026-04-21T19:14:59.627489Z","end":"2026-04-21T19:14:59.786948Z","steps":["trace[1234085201] 'read index received' (duration: 159.271626ms)","trace[1234085201] 'applied index is now lower than readState.Index' (duration: 185.972µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:14:59.787039Z","caller":"traceutil/trace.go:171","msg":"trace[970720946] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"191.202475ms","start":"2026-04-21T19:14:59.595799Z","end":"2026-04-21T19:14:59.787002Z","steps":["trace[970720946] 'process raft request' (duration: 191.011903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:14:59.787254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.7988ms","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-21T19:14:59.787286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.773302ms","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-21T19:14:59.787316Z","caller":"traceutil/trace.go:171","msg":"trace[1831645661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"154.904091ms","start":"2026-04-21T19:14:59.632396Z","end":"2026-04-21T19:14:59.7873Z","steps":["trace[1831645661] 'agreement among raft nodes before linearized reading' (duration: 154.696108ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:14:59.787373Z","caller":"traceutil/trace.go:171","msg":"trace[170809191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"159.911653ms","start":"2026-04-21T19:14:59.627439Z","end":"2026-04-21T19:14:59.787351Z","steps":["trace[170809191] 'agreement among raft nodes before linearized reading' (duration: 159.63663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:15:00.206192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.91866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:15:00.206319Z","caller":"traceutil/trace.go:171","msg":"trace[1350283688] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"415.007181ms","start":"2026-04-21T19:14:59.79129Z","end":"2026-04-21T19:15:00.206297Z","steps":["trace[1350283688] 'process raft request' (duration: 90.894588ms)","trace[1350283688] 'compare' (duration: 323.554546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:15:00.20638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:59.791266Z","time spent":"415.091792ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:15:00.298168Z","caller":"traceutil/trace.go:171","msg":"trace[913738704] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"506.481703ms","start":"2026-04-21T19:14:59.791659Z","end":"2026-04-21T19:15:00.298141Z","steps":["trace[913738704] 'process raft request' (duration: 506.21563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:15:00.298329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:14:59.791636Z","time spent":"506.608525ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:15:01.178942Z","caller":"traceutil/trace.go:171","msg":"trace[2007557654] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"183.07347ms","start":"2026-04-21T19:15:00.995837Z","end":"2026-04-21T19:15:01.178911Z","steps":["trace[2007557654] 'process raft request' (duration: 99.146195ms)","trace[2007557654] 'compare' (duration: 83.56347ms)"],"step_count":2}