{"level":"warn","ts":"2026-04-27T21:00:08.253854Z","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-27T21:00:08.253994Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.25:2380","--initial-cluster=instance=https://199.19.213.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.25: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-27T21:00:08.254105Z","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-27T21:00:08.25413Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-04-27T21:00:08.254161Z","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-27T21:00:08.25546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"]} {"level":"info","ts":"2026-04-27T21:00:08.255599Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.25: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-27T21:00:08.261187Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.208347ms"} {"level":"info","ts":"2026-04-27T21:00:08.268874Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd4db28c48efaf1","cluster-id":"b621f59192c78404"} {"level":"info","ts":"2026-04-27T21:00:08.268954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T21:00:08.268979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 0"} {"level":"info","ts":"2026-04-27T21:00:08.268999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd4db28c48efaf1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T21:00:08.269217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 1"} {"level":"info","ts":"2026-04-27T21:00:08.269262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"warn","ts":"2026-04-27T21:00:08.281715Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T21:00:08.284727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T21:00:08.286006Z","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-27T21:00:08.288599Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd4db28c48efaf1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T21:00:08.293339Z","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-27T21:00:08.293424Z","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-27T21:00:08.293436Z","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-27T21:00:08.293763Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd4db28c48efaf1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T21:00:08.294113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"info","ts":"2026-04-27T21:00:08.294225Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","added-peer-id":"9bd4db28c48efaf1","added-peer-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-04-27T21:00:08.294548Z","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-27T21:00:08.2948Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd4db28c48efaf1","initial-advertise-peer-urls":["https://199.19.213.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T21:00:08.29483Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T21:00:08.294936Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-04-27T21:00:08.294949Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-04-27T21:00:08.570437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T21:00:08.5705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T21:00:08.570534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgPreVoteResp from 9bd4db28c48efaf1 at term 1"} {"level":"info","ts":"2026-04-27T21:00:08.57055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became candidate at term 2"} {"level":"info","ts":"2026-04-27T21:00:08.570556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgVoteResp from 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-04-27T21:00:08.570566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became leader at term 2"} {"level":"info","ts":"2026-04-27T21:00:08.570578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd4db28c48efaf1 elected leader 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-04-27T21:00:08.571438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:08.572356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd4db28c48efaf1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.25:2379]}","request-path":"/0/members/9bd4db28c48efaf1/attributes","cluster-id":"b621f59192c78404","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T21:00:08.572548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:08.572813Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:08.572679Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:08.572896Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:08.573202Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:08.5734Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T21:00:08.573489Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T21:00:08.576968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.25:2379"} {"level":"info","ts":"2026-04-27T21:00:08.578889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-27T21:00:12.272447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.087115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T21:00:12.272568Z","caller":"traceutil/trace.go:171","msg":"trace[840592102] linearizableReadLoop","detail":"{readStateIndex:124; appliedIndex:123; }","duration":"1.257293987s","start":"2026-04-27T21:00:11.015257Z","end":"2026-04-27T21:00:12.272551Z","steps":["trace[840592102] 'read index received' (duration: 297.447348ms)","trace[840592102] 'applied index is now lower than readState.Index' (duration: 959.845289ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:00:12.272663Z","caller":"traceutil/trace.go:171","msg":"trace[1696666451] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"1.319105106s","start":"2026-04-27T21:00:10.953548Z","end":"2026-04-27T21:00:12.272653Z","steps":["trace[1696666451] 'process raft request' (duration: 359.205907ms)","trace[1696666451] 'compare' (duration: 958.904013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:12.27273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:10.953537Z","time spent":"1.319152347s","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T21:00:12.272977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.97162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T21:00:12.273017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257727533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T21:00:12.273054Z","caller":"traceutil/trace.go:171","msg":"trace[949620213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:119; }","duration":"220.104252ms","start":"2026-04-27T21:00:12.052936Z","end":"2026-04-27T21:00:12.27304Z","steps":["trace[949620213] 'agreement among raft nodes before linearized reading' (duration: 219.930029ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:12.273094Z","caller":"traceutil/trace.go:171","msg":"trace[721807777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:119; }","duration":"1.257832205s","start":"2026-04-27T21:00:11.015242Z","end":"2026-04-27T21:00:12.273074Z","steps":["trace[721807777] 'agreement among raft nodes before linearized reading' (duration: 1.257636382s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:12.273135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:11.015197Z","time spent":"1.257930607s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:00:12.501512Z","caller":"traceutil/trace.go:171","msg":"trace[672888188] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"152.613568ms","start":"2026-04-27T21:00:12.348878Z","end":"2026-04-27T21:00:12.501492Z","steps":["trace[672888188] 'process raft request' (duration: 151.358688ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.051427Z","caller":"traceutil/trace.go:171","msg":"trace[304366918] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"156.097011ms","start":"2026-04-27T21:00:38.895306Z","end":"2026-04-27T21:00:39.051403Z","steps":["trace[304366918] 'process raft request' (duration: 155.861357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.0579Z","caller":"traceutil/trace.go:171","msg":"trace[1181955463] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"156.549688ms","start":"2026-04-27T21:00:38.901322Z","end":"2026-04-27T21:00:39.057871Z","steps":["trace[1181955463] 'process raft request' (duration: 156.283372ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058037Z","caller":"traceutil/trace.go:171","msg":"trace[374221832] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"156.430266ms","start":"2026-04-27T21:00:38.901572Z","end":"2026-04-27T21:00:39.058002Z","steps":["trace[374221832] 'process raft request' (duration: 156.194691ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058056Z","caller":"traceutil/trace.go:171","msg":"trace[484663811] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"154.759237ms","start":"2026-04-27T21:00:38.903272Z","end":"2026-04-27T21:00:39.058031Z","steps":["trace[484663811] 'process raft request' (duration: 154.584785ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058255Z","caller":"traceutil/trace.go:171","msg":"trace[1640201105] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"154.634096ms","start":"2026-04-27T21:00:38.903608Z","end":"2026-04-27T21:00:39.058242Z","steps":["trace[1640201105] 'process raft request' (duration: 154.373162ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058354Z","caller":"traceutil/trace.go:171","msg":"trace[1914993996] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"150.722909ms","start":"2026-04-27T21:00:38.907622Z","end":"2026-04-27T21:00:39.058345Z","steps":["trace[1914993996] 'process raft request' (duration: 150.530905ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058443Z","caller":"traceutil/trace.go:171","msg":"trace[658041069] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"138.653912ms","start":"2026-04-27T21:00:38.919779Z","end":"2026-04-27T21:00:39.058433Z","steps":["trace[658041069] 'process raft request' (duration: 138.480409ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058471Z","caller":"traceutil/trace.go:171","msg":"trace[710090536] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"150.680018ms","start":"2026-04-27T21:00:38.90774Z","end":"2026-04-27T21:00:39.05842Z","steps":["trace[710090536] 'process raft request' (duration: 150.482505ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058566Z","caller":"traceutil/trace.go:171","msg":"trace[753809242] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"135.002439ms","start":"2026-04-27T21:00:38.92355Z","end":"2026-04-27T21:00:39.058553Z","steps":["trace[753809242] 'process raft request' (duration: 134.751435ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.058635Z","caller":"traceutil/trace.go:171","msg":"trace[1563401903] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"106.954879ms","start":"2026-04-27T21:00:38.951674Z","end":"2026-04-27T21:00:39.058629Z","steps":["trace[1563401903] 'process raft request' (duration: 106.741696ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.2509Z","caller":"traceutil/trace.go:171","msg":"trace[527692815] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"181.038707ms","start":"2026-04-27T21:00:39.06984Z","end":"2026-04-27T21:00:39.250878Z","steps":["trace[527692815] 'process raft request' (duration: 180.070701ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.250128Z","caller":"traceutil/trace.go:171","msg":"trace[1631806890] transaction","detail":"{read_only:false; number_of_response:0; response_revision:528; }","duration":"184.858312ms","start":"2026-04-27T21:00:39.065265Z","end":"2026-04-27T21:00:39.250124Z","steps":["trace[1631806890] 'process raft request' (duration: 175.340899ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.250111Z","caller":"traceutil/trace.go:171","msg":"trace[626872790] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"175.794168ms","start":"2026-04-27T21:00:39.074299Z","end":"2026-04-27T21:00:39.250093Z","steps":["trace[626872790] 'process raft request' (duration: 175.755757ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.256861Z","caller":"traceutil/trace.go:171","msg":"trace[2002437452] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:551; }","duration":"168.660716ms","start":"2026-04-27T21:00:39.088159Z","end":"2026-04-27T21:00:39.25682Z","steps":["trace[2002437452] 'read index received' (duration: 152.667742ms)","trace[2002437452] 'applied index is now lower than readState.Index' (duration: 15.992244ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:00:39.257205Z","caller":"traceutil/trace.go:171","msg":"trace[1538644176] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"169.320366ms","start":"2026-04-27T21:00:39.087874Z","end":"2026-04-27T21:00:39.257194Z","steps":["trace[1538644176] 'process raft request' (duration: 163.1562ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.257358Z","caller":"traceutil/trace.go:171","msg":"trace[1901328529] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"143.096828ms","start":"2026-04-27T21:00:39.114253Z","end":"2026-04-27T21:00:39.25735Z","steps":["trace[1901328529] 'process raft request' (duration: 142.373585ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:39.257483Z","caller":"traceutil/trace.go:171","msg":"trace[1663353338] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"124.046082ms","start":"2026-04-27T21:00:39.133429Z","end":"2026-04-27T21:00:39.257475Z","steps":["trace[1663353338] 'process raft request' (duration: 123.252619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:39.257719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.539541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-27T21:00:39.257769Z","caller":"traceutil/trace.go:171","msg":"trace[1584866301] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:534; }","duration":"169.611902ms","start":"2026-04-27T21:00:39.088142Z","end":"2026-04-27T21:00:39.257754Z","steps":["trace[1584866301] 'agreement among raft nodes before linearized reading' (duration: 169.473869ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:44.337695Z","caller":"traceutil/trace.go:171","msg":"trace[1013484655] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"274.631088ms","start":"2026-04-27T21:00:44.063041Z","end":"2026-04-27T21:00:44.337672Z","steps":["trace[1013484655] 'process raft request' (duration: 274.199241ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:45.512618Z","caller":"traceutil/trace.go:171","msg":"trace[266312876] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"172.988679ms","start":"2026-04-27T21:00:45.339608Z","end":"2026-04-27T21:00:45.512597Z","steps":["trace[266312876] 'process raft request' (duration: 172.394159ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:45.825237Z","caller":"traceutil/trace.go:171","msg":"trace[1664884783] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"295.909952ms","start":"2026-04-27T21:00:45.529299Z","end":"2026-04-27T21:00:45.825209Z","steps":["trace[1664884783] 'read index received' (duration: 295.859631ms)","trace[1664884783] 'applied index is now lower than readState.Index' (duration: 48.821µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:45.825395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.072344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:45.825411Z","caller":"traceutil/trace.go:171","msg":"trace[1849258013] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"465.817669ms","start":"2026-04-27T21:00:45.359504Z","end":"2026-04-27T21:00:45.825321Z","steps":["trace[1849258013] 'process raft request' (duration: 465.552164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:45.825445Z","caller":"traceutil/trace.go:171","msg":"trace[1061871606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"296.160265ms","start":"2026-04-27T21:00:45.529268Z","end":"2026-04-27T21:00:45.825429Z","steps":["trace[1061871606] 'agreement among raft nodes before linearized reading' (duration: 296.068744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:45.826099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:45.35948Z","time spent":"466.095873ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:00:45.839882Z","caller":"traceutil/trace.go:171","msg":"trace[1622236677] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"270.572068ms","start":"2026-04-27T21:00:45.569295Z","end":"2026-04-27T21:00:45.839867Z","steps":["trace[1622236677] 'process raft request' (duration: 270.506227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:46.840991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.917866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:00:46.841152Z","caller":"traceutil/trace.go:171","msg":"trace[1596164698] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"958.071379ms","start":"2026-04-27T21:00:45.883056Z","end":"2026-04-27T21:00:46.841127Z","steps":["trace[1596164698] 'process raft request' (duration: 113.91967ms)","trace[1596164698] 'compare' (duration: 843.771993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:46.841232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:45.883047Z","time spent":"958.145091ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:00:46.858923Z","caller":"traceutil/trace.go:171","msg":"trace[2102586036] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"327.476252ms","start":"2026-04-27T21:00:46.531423Z","end":"2026-04-27T21:00:46.858899Z","steps":["trace[2102586036] 'read index received' (duration: 326.69745ms)","trace[2102586036] 'applied index is now lower than readState.Index' (duration: 778.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:00:46.858981Z","caller":"traceutil/trace.go:171","msg":"trace[127066925] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"609.272202ms","start":"2026-04-27T21:00:46.249673Z","end":"2026-04-27T21:00:46.858945Z","steps":["trace[127066925] 'process raft request' (duration: 608.688422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:46.859052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.616314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:46.85909Z","caller":"traceutil/trace.go:171","msg":"trace[1302876057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"327.716176ms","start":"2026-04-27T21:00:46.531362Z","end":"2026-04-27T21:00:46.859078Z","steps":["trace[1302876057] 'agreement among raft nodes before linearized reading' (duration: 327.633585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:46.859134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:46.531338Z","time spent":"327.787267ms","remote":"127.0.0.1:42002","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-27T21:00:46.859156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:46.249648Z","time spent":"609.415065ms","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:00:48.462835Z","caller":"traceutil/trace.go:171","msg":"trace[962121380] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"139.13238ms","start":"2026-04-27T21:00:48.323684Z","end":"2026-04-27T21:00:48.462816Z","steps":["trace[962121380] 'process raft request' (duration: 139.034049ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:48.466077Z","caller":"traceutil/trace.go:171","msg":"trace[2046111071] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"135.224113ms","start":"2026-04-27T21:00:48.330828Z","end":"2026-04-27T21:00:48.466053Z","steps":["trace[2046111071] 'read index received' (duration: 131.948238ms)","trace[2046111071] 'applied index is now lower than readState.Index' (duration: 3.274605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:48.46623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.371406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:48.466263Z","caller":"traceutil/trace.go:171","msg":"trace[1302195488] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:649; }","duration":"135.455338ms","start":"2026-04-27T21:00:48.3308Z","end":"2026-04-27T21:00:48.466255Z","steps":["trace[1302195488] 'agreement among raft nodes before linearized reading' (duration: 135.368656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:48.466322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.389736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:48.466432Z","caller":"traceutil/trace.go:171","msg":"trace[1076724108] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:649; }","duration":"135.5809ms","start":"2026-04-27T21:00:48.330812Z","end":"2026-04-27T21:00:48.466393Z","steps":["trace[1076724108] 'agreement among raft nodes before linearized reading' (duration: 135.379207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:48.466565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.38119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:48.466596Z","caller":"traceutil/trace.go:171","msg":"trace[477756481] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:649; }","duration":"106.460921ms","start":"2026-04-27T21:00:48.360128Z","end":"2026-04-27T21:00:48.466589Z","steps":["trace[477756481] 'agreement among raft nodes before linearized reading' (duration: 106.41121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:48.466689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.562283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:00:48.466714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.407884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:48.466739Z","caller":"traceutil/trace.go:171","msg":"trace[914863843] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:649; }","duration":"105.460324ms","start":"2026-04-27T21:00:48.361272Z","end":"2026-04-27T21:00:48.466732Z","steps":["trace[914863843] 'agreement among raft nodes before linearized reading' (duration: 105.421783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:48.466799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.984519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:00:48.466721Z","caller":"traceutil/trace.go:171","msg":"trace[545372246] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:649; }","duration":"106.596314ms","start":"2026-04-27T21:00:48.360117Z","end":"2026-04-27T21:00:48.466713Z","steps":["trace[545372246] 'agreement among raft nodes before linearized reading' (duration: 106.547352ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:48.466826Z","caller":"traceutil/trace.go:171","msg":"trace[1336858151] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:649; }","duration":"135.029341ms","start":"2026-04-27T21:00:48.331791Z","end":"2026-04-27T21:00:48.466821Z","steps":["trace[1336858151] 'agreement among raft nodes before linearized reading' (duration: 134.96693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:32.244748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.02569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T21:01:32.244858Z","caller":"traceutil/trace.go:171","msg":"trace[1040907603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"173.145651ms","start":"2026-04-27T21:01:32.071693Z","end":"2026-04-27T21:01:32.244839Z","steps":["trace[1040907603] 'range keys from in-memory index tree' (duration: 172.835321ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:32.465218Z","caller":"traceutil/trace.go:171","msg":"trace[1637525813] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"137.538696ms","start":"2026-04-27T21:01:32.327658Z","end":"2026-04-27T21:01:32.465197Z","steps":["trace[1637525813] 'read index received' (duration: 137.438462ms)","trace[1637525813] 'applied index is now lower than readState.Index' (duration: 99.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:32.465313Z","caller":"traceutil/trace.go:171","msg":"trace[1109788968] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"216.755405ms","start":"2026-04-27T21:01:32.24852Z","end":"2026-04-27T21:01:32.465276Z","steps":["trace[1109788968] 'process raft request' (duration: 216.526642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:32.465785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.047754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T21:01:32.465941Z","caller":"traceutil/trace.go:171","msg":"trace[1278217486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"138.306332ms","start":"2026-04-27T21:01:32.327611Z","end":"2026-04-27T21:01:32.465917Z","steps":["trace[1278217486] 'agreement among raft nodes before linearized reading' (duration: 137.708909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:07.783698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.944619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:02:07.784356Z","caller":"traceutil/trace.go:171","msg":"trace[550580327] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"473.278891ms","start":"2026-04-27T21:02:07.311056Z","end":"2026-04-27T21:02:07.784335Z","steps":["trace[550580327] 'read index received' (duration: 217.608958ms)","trace[550580327] 'applied index is now lower than readState.Index' (duration: 255.668603ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:02:07.784475Z","caller":"traceutil/trace.go:171","msg":"trace[75847462] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"507.38497ms","start":"2026-04-27T21:02:07.277076Z","end":"2026-04-27T21:02:07.784461Z","steps":["trace[75847462] 'process raft request' (duration: 507.185396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:07.784571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:02:07.27704Z","time spent":"507.478982ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:02:07.784951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.40907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:02:07.785058Z","caller":"traceutil/trace.go:171","msg":"trace[947468053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"253.533082ms","start":"2026-04-27T21:02:07.531509Z","end":"2026-04-27T21:02:07.785042Z","steps":["trace[947468053] 'agreement among raft nodes before linearized reading' (duration: 253.396089ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:07.785243Z","caller":"traceutil/trace.go:171","msg":"trace[1020108174] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"508.288867ms","start":"2026-04-27T21:02:07.276844Z","end":"2026-04-27T21:02:07.785133Z","steps":["trace[1020108174] 'process raft request' (duration: 251.813569ms)","trace[1020108174] 'compare' (duration: 254.808277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:07.785552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.486854ms","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-27T21:02:07.786368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:02:07.276824Z","time spent":"509.474121ms","remote":"127.0.0.1:42274","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-27T21:02:07.786417Z","caller":"traceutil/trace.go:171","msg":"trace[1126318025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"475.38251ms","start":"2026-04-27T21:02:07.311017Z","end":"2026-04-27T21:02:07.7864Z","steps":["trace[1126318025] 'agreement among raft nodes before linearized reading' (duration: 473.797419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:07.786463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:02:07.310999Z","time spent":"475.449792ms","remote":"127.0.0.1:42274","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-27T21:02:29.611845Z","caller":"traceutil/trace.go:171","msg":"trace[659649665] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"270.736581ms","start":"2026-04-27T21:02:29.341087Z","end":"2026-04-27T21:02:29.611824Z","steps":["trace[659649665] 'process raft request' (duration: 270.310592ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:31.777104Z","caller":"traceutil/trace.go:171","msg":"trace[1181493329] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"160.064951ms","start":"2026-04-27T21:02:31.617018Z","end":"2026-04-27T21:02:31.777083Z","steps":["trace[1181493329] 'process raft request' (duration: 159.945368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:32.184591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.579609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T21:02:32.184675Z","caller":"traceutil/trace.go:171","msg":"trace[1259164121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"225.700991ms","start":"2026-04-27T21:02:31.958953Z","end":"2026-04-27T21:02:32.184654Z","steps":["trace[1259164121] 'range keys from in-memory index tree' (duration: 225.07185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:32.184579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.068375ms","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-27T21:02:32.184662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.193931ms","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-27T21:02:32.184883Z","caller":"traceutil/trace.go:171","msg":"trace[1848645421] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"178.917719ms","start":"2026-04-27T21:02:32.005952Z","end":"2026-04-27T21:02:32.184869Z","steps":["trace[1848645421] 'process raft request' (duration: 155.653096ms)","trace[1848645421] 'compare' (duration: 22.52725ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:02:32.184927Z","caller":"traceutil/trace.go:171","msg":"trace[403117621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"209.707836ms","start":"2026-04-27T21:02:31.975204Z","end":"2026-04-27T21:02:32.184911Z","steps":["trace[403117621] 'range keys from in-memory index tree' (duration: 208.943602ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:32.185054Z","caller":"traceutil/trace.go:171","msg":"trace[157352633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"236.543897ms","start":"2026-04-27T21:02:31.948392Z","end":"2026-04-27T21:02:32.184936Z","steps":["trace[157352633] 'range keys from in-memory index tree' (duration: 235.919676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:45.432973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407498736261118,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:02:45.469609Z","caller":"traceutil/trace.go:171","msg":"trace[666892341] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"536.908013ms","start":"2026-04-27T21:02:44.932682Z","end":"2026-04-27T21:02:45.46959Z","steps":["trace[666892341] 'read index received' (duration: 536.791451ms)","trace[666892341] 'applied index is now lower than readState.Index' (duration: 115.762µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:45.469769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.064356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T21:02:45.469795Z","caller":"traceutil/trace.go:171","msg":"trace[1800149244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"537.133657ms","start":"2026-04-27T21:02:44.932653Z","end":"2026-04-27T21:02:45.469787Z","steps":["trace[1800149244] 'agreement among raft nodes before linearized reading' (duration: 537.013995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:45.469821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:02:44.932641Z","time spent":"537.173918ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T21:02:45.469928Z","caller":"traceutil/trace.go:171","msg":"trace[957215951] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"633.965042ms","start":"2026-04-27T21:02:44.835922Z","end":"2026-04-27T21:02:45.469887Z","steps":["trace[957215951] 'process raft request' (duration: 633.551295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:45.470098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:02:44.835906Z","time spent":"634.110365ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:03:01.667097Z","caller":"traceutil/trace.go:171","msg":"trace[1581899075] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"125.629745ms","start":"2026-04-27T21:03:01.54145Z","end":"2026-04-27T21:03:01.66708Z","steps":["trace[1581899075] 'read index received' (duration: 125.539233ms)","trace[1581899075] 'applied index is now lower than readState.Index' (duration: 89.812µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:01.667192Z","caller":"traceutil/trace.go:171","msg":"trace[1812428577] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"128.085621ms","start":"2026-04-27T21:03:01.53908Z","end":"2026-04-27T21:03:01.667165Z","steps":["trace[1812428577] 'process raft request' (duration: 127.899488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:01.667243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.773948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T21:03:01.667264Z","caller":"traceutil/trace.go:171","msg":"trace[1987894914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"125.830879ms","start":"2026-04-27T21:03:01.541427Z","end":"2026-04-27T21:03:01.667258Z","steps":["trace[1987894914] 'agreement among raft nodes before linearized reading' (duration: 125.718097ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:02.406669Z","caller":"traceutil/trace.go:171","msg":"trace[1169318191] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"269.148239ms","start":"2026-04-27T21:03:02.137488Z","end":"2026-04-27T21:03:02.406636Z","steps":["trace[1169318191] 'process raft request' (duration: 268.220302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.741152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.742916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:03:02.741346Z","caller":"traceutil/trace.go:171","msg":"trace[480131570] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"326.93037ms","start":"2026-04-27T21:03:02.414399Z","end":"2026-04-27T21:03:02.74133Z","steps":["trace[480131570] 'process raft request' (duration: 326.864909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.741417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.414386Z","time spent":"327.002691ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T21:03:02.741389Z","caller":"traceutil/trace.go:171","msg":"trace[1845529235] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"440.012185ms","start":"2026-04-27T21:03:02.301323Z","end":"2026-04-27T21:03:02.741335Z","steps":["trace[1845529235] 'process raft request' (duration: 219.017045ms)","trace[1845529235] 'compare' (duration: 220.584753ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:02.74148Z","caller":"traceutil/trace.go:171","msg":"trace[1017897241] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"336.3783ms","start":"2026-04-27T21:03:02.405073Z","end":"2026-04-27T21:03:02.741451Z","steps":["trace[1017897241] 'read index received' (duration: 1.01881ms)","trace[1017897241] 'applied index is now lower than readState.Index' (duration: 335.35631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:03:02.741548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.301298Z","time spent":"440.172279ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T21:03:02.741721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.628836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T21:03:02.741783Z","caller":"traceutil/trace.go:171","msg":"trace[553924574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"336.701887ms","start":"2026-04-27T21:03:02.405067Z","end":"2026-04-27T21:03:02.741769Z","steps":["trace[553924574] 'agreement among raft nodes before linearized reading' (duration: 336.489333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.741816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.40502Z","time spent":"336.789869ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:03:02.743397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.475425ms","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-27T21:03:02.743427Z","caller":"traceutil/trace.go:171","msg":"trace[1957618831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"333.534317ms","start":"2026-04-27T21:03:02.409884Z","end":"2026-04-27T21:03:02.743419Z","steps":["trace[1957618831] 'agreement among raft nodes before linearized reading' (duration: 333.450535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.743446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.40987Z","time spent":"333.570647ms","remote":"127.0.0.1:42274","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-27T21:03:02.743407Z","caller":"traceutil/trace.go:171","msg":"trace[596788256] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"100.466145ms","start":"2026-04-27T21:03:02.64292Z","end":"2026-04-27T21:03:02.743386Z","steps":["trace[596788256] 'process raft request' (duration: 100.332233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.74358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.732213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-27T21:03:02.743605Z","caller":"traceutil/trace.go:171","msg":"trace[1493875046] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"330.781224ms","start":"2026-04-27T21:03:02.412818Z","end":"2026-04-27T21:03:02.743599Z","steps":["trace[1493875046] 'agreement among raft nodes before linearized reading' (duration: 330.708353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.743627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.412812Z","time spent":"330.806774ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T21:03:02.743807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.095978ms","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-27T21:03:02.743845Z","caller":"traceutil/trace.go:171","msg":"trace[1856829074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"333.155189ms","start":"2026-04-27T21:03:02.410679Z","end":"2026-04-27T21:03:02.743834Z","steps":["trace[1856829074] 'agreement among raft nodes before linearized reading' (duration: 333.017387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:02.743874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:02.410673Z","time spent":"333.19437ms","remote":"127.0.0.1:42274","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-27T21:03:03.805264Z","caller":"traceutil/trace.go:171","msg":"trace[1922882147] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"111.141597ms","start":"2026-04-27T21:03:03.694092Z","end":"2026-04-27T21:03:03.805234Z","steps":["trace[1922882147] 'read index received' (duration: 110.990415ms)","trace[1922882147] 'applied index is now lower than readState.Index' (duration: 150.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:03.805362Z","caller":"traceutil/trace.go:171","msg":"trace[36093853] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"131.24074ms","start":"2026-04-27T21:03:03.674103Z","end":"2026-04-27T21:03:03.805343Z","steps":["trace[36093853] 'process raft request' (duration: 130.967495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:03.805503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.399953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T21:03:03.805564Z","caller":"traceutil/trace.go:171","msg":"trace[1378151618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"111.512214ms","start":"2026-04-27T21:03:03.694034Z","end":"2026-04-27T21:03:03.805546Z","steps":["trace[1378151618] 'agreement among raft nodes before linearized reading' (duration: 111.30941ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:04.059104Z","caller":"traceutil/trace.go:171","msg":"trace[1970064971] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"249.161719ms","start":"2026-04-27T21:03:03.809917Z","end":"2026-04-27T21:03:04.059079Z","steps":["trace[1970064971] 'process raft request' (duration: 249.011755ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:15.070516Z","caller":"traceutil/trace.go:171","msg":"trace[1430943655] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"208.215588ms","start":"2026-04-27T21:03:14.862277Z","end":"2026-04-27T21:03:15.070492Z","steps":["trace[1430943655] 'read index received' (duration: 208.043474ms)","trace[1430943655] 'applied index is now lower than readState.Index' (duration: 168.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:15.070567Z","caller":"traceutil/trace.go:171","msg":"trace[1670009640] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"208.499982ms","start":"2026-04-27T21:03:14.862032Z","end":"2026-04-27T21:03:15.070532Z","steps":["trace[1670009640] 'process raft request' (duration: 208.315389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:15.070728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.426381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T21:03:15.070772Z","caller":"traceutil/trace.go:171","msg":"trace[1884161712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1417; }","duration":"208.515602ms","start":"2026-04-27T21:03:14.862246Z","end":"2026-04-27T21:03:15.070761Z","steps":["trace[1884161712] 'agreement among raft nodes before linearized reading' (duration: 208.340659ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:16.00178Z","caller":"traceutil/trace.go:171","msg":"trace[984579547] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"1.138369924s","start":"2026-04-27T21:03:14.863389Z","end":"2026-04-27T21:03:16.001759Z","steps":["trace[984579547] 'process raft request' (duration: 1.134431628s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:16.001947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:14.863376Z","time spent":"1.138471075s","remote":"127.0.0.1:42274","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-27T21:03:16.003159Z","caller":"traceutil/trace.go:171","msg":"trace[805224061] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"928.545066ms","start":"2026-04-27T21:03:15.074598Z","end":"2026-04-27T21:03:16.003143Z","steps":["trace[805224061] 'process raft request' (duration: 927.888264ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:16.002608Z","caller":"traceutil/trace.go:171","msg":"trace[717503254] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1478; }","duration":"500.289146ms","start":"2026-04-27T21:03:15.502296Z","end":"2026-04-27T21:03:16.002585Z","steps":["trace[717503254] 'read index received' (duration: 495.483914ms)","trace[717503254] 'applied index is now lower than readState.Index' (duration: 4.804182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:03:16.00327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.967418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-27T21:03:16.003274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:15.074577Z","time spent":"928.626737ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:03:16.003324Z","caller":"traceutil/trace.go:171","msg":"trace[885328901] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1419; }","duration":"501.063279ms","start":"2026-04-27T21:03:15.502248Z","end":"2026-04-27T21:03:16.003311Z","steps":["trace[885328901] 'agreement among raft nodes before linearized reading' (duration: 500.868336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:16.003493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.947873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-27T21:03:16.003531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.524369ms","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-27T21:03:16.003566Z","caller":"traceutil/trace.go:171","msg":"trace[2139866404] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1419; }","duration":"442.050794ms","start":"2026-04-27T21:03:15.561494Z","end":"2026-04-27T21:03:16.003545Z","steps":["trace[2139866404] 'agreement among raft nodes before linearized reading' (duration: 441.852001ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:16.003608Z","caller":"traceutil/trace.go:171","msg":"trace[4919407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1419; }","duration":"149.610272ms","start":"2026-04-27T21:03:15.853958Z","end":"2026-04-27T21:03:16.003568Z","steps":["trace[4919407] 'agreement among raft nodes before linearized reading' (duration: 149.430578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:16.003613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:15.561449Z","time spent":"442.156228ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T21:03:16.004338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:15.502229Z","time spent":"501.131091ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T21:03:30.249189Z","caller":"traceutil/trace.go:171","msg":"trace[814608925] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"184.067427ms","start":"2026-04-27T21:03:30.065104Z","end":"2026-04-27T21:03:30.249171Z","steps":["trace[814608925] 'read index received' (duration: 183.968375ms)","trace[814608925] 'applied index is now lower than readState.Index' (duration: 96.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:30.249302Z","caller":"traceutil/trace.go:171","msg":"trace[848677105] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"185.438264ms","start":"2026-04-27T21:03:30.063829Z","end":"2026-04-27T21:03:30.249267Z","steps":["trace[848677105] 'process raft request' (duration: 185.224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:30.249353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.239492ms","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-27T21:03:30.249377Z","caller":"traceutil/trace.go:171","msg":"trace[1226931632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1483; }","duration":"184.306753ms","start":"2026-04-27T21:03:30.065063Z","end":"2026-04-27T21:03:30.24937Z","steps":["trace[1226931632] 'agreement among raft nodes before linearized reading' (duration: 184.204351ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:30.280923Z","caller":"traceutil/trace.go:171","msg":"trace[1252610627] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"125.111336ms","start":"2026-04-27T21:03:30.155785Z","end":"2026-04-27T21:03:30.280897Z","steps":["trace[1252610627] 'process raft request' (duration: 124.950653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:30.280991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.796081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T21:03:30.281086Z","caller":"traceutil/trace.go:171","msg":"trace[2032378693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1484; }","duration":"196.948944ms","start":"2026-04-27T21:03:30.084119Z","end":"2026-04-27T21:03:30.281068Z","steps":["trace[2032378693] 'agreement among raft nodes before linearized reading' (duration: 196.71607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:30.281244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.842241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T21:03:30.281439Z","caller":"traceutil/trace.go:171","msg":"trace[1182850579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1484; }","duration":"113.050976ms","start":"2026-04-27T21:03:30.168333Z","end":"2026-04-27T21:03:30.281384Z","steps":["trace[1182850579] 'agreement among raft nodes before linearized reading' (duration: 112.610448ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:06:35.261788Z","caller":"traceutil/trace.go:171","msg":"trace[1819352212] linearizableReadLoop","detail":"{readStateIndex:2463; appliedIndex:2462; }","duration":"171.012852ms","start":"2026-04-27T21:06:35.09075Z","end":"2026-04-27T21:06:35.261763Z","steps":["trace[1819352212] 'read index received' (duration: 170.807988ms)","trace[1819352212] 'applied index is now lower than readState.Index' (duration: 203.994µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:06:35.26182Z","caller":"traceutil/trace.go:171","msg":"trace[629403943] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"279.762577ms","start":"2026-04-27T21:06:34.982039Z","end":"2026-04-27T21:06:35.261801Z","steps":["trace[629403943] 'process raft request' (duration: 279.584703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:06:35.262044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.278107ms","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-27T21:06:35.262108Z","caller":"traceutil/trace.go:171","msg":"trace[1763647179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2361; }","duration":"171.370239ms","start":"2026-04-27T21:06:35.090715Z","end":"2026-04-27T21:06:35.262086Z","steps":["trace[1763647179] 'agreement among raft nodes before linearized reading' (duration: 171.197746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:06:56.060175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.95398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"warn","ts":"2026-04-27T21:06:56.060227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.428255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:06:56.060275Z","caller":"traceutil/trace.go:171","msg":"trace[710669639] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:2465; }","duration":"144.093713ms","start":"2026-04-27T21:06:55.916156Z","end":"2026-04-27T21:06:56.06025Z","steps":["trace[710669639] 'range keys from in-memory index tree' (duration: 143.835137ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:06:56.060309Z","caller":"traceutil/trace.go:171","msg":"trace[1557645424] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2465; }","duration":"171.544457ms","start":"2026-04-27T21:06:55.888742Z","end":"2026-04-27T21:06:56.060286Z","steps":["trace[1557645424] 'range keys from in-memory index tree' (duration: 171.353913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:06:56.060319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.032935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-04-27T21:06:56.060376Z","caller":"traceutil/trace.go:171","msg":"trace[750236414] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2465; }","duration":"195.123727ms","start":"2026-04-27T21:06:55.865238Z","end":"2026-04-27T21:06:56.060361Z","steps":["trace[750236414] 'range keys from in-memory index tree' (duration: 194.891512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:06:56.060478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.532654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-04-27T21:06:56.060546Z","caller":"traceutil/trace.go:171","msg":"trace[752665426] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:2465; }","duration":"151.611136ms","start":"2026-04-27T21:06:55.908916Z","end":"2026-04-27T21:06:56.060527Z","steps":["trace[752665426] 'range keys from in-memory index tree' (duration: 151.367961ms)"],"step_count":1}