{"level":"warn","ts":"2026-05-28T00:38:20.60091Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:38:20.60103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-05-28T00:38:20.601124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:38:20.601153Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-28T00:38:20.601184Z","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-05-28T00:38:20.601828Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-28T00:38:20.60193Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-05-28T00:38:20.611717Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.552833ms"} {"level":"info","ts":"2026-05-28T00:38:20.644017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-28T00:38:20.644186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T00:38:20.644247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-28T00:38:20.644284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T00:38:20.644326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-28T00:38:20.644405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-28T00:38:20.746806Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T00:38:20.755057Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T00:38:20.768753Z","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-05-28T00:38:20.786207Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T00:38:20.786563Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T00:38:20.786822Z","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-05-28T00:38:20.787004Z","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-05-28T00:38:20.787149Z","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-05-28T00:38:20.788448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-28T00:38:20.788724Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-28T00:38:20.792494Z","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-05-28T00:38:20.793034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-28T00:38:20.793163Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-28T00:38:20.804994Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T00:38:20.805023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T00:38:21.145467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T00:38:21.145548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T00:38:21.145596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-28T00:38:21.145617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-28T00:38:21.145628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-28T00:38:21.145656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-28T00:38:21.14567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-28T00:38:21.163695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T00:38:21.163966Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:38:21.164128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:38:21.164263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:38:21.166626Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:38:21.166819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:38:21.166946Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:38:21.167088Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T00:38:21.167168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T00:38:21.168431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-28T00:38:21.168893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T00:38:23.049092Z","caller":"traceutil/trace.go:171","msg":"trace[1422216260] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"223.94933ms","start":"2026-05-28T00:38:22.825119Z","end":"2026-05-28T00:38:23.049068Z","steps":["trace[1422216260] 'process raft request' (duration: 223.912849ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:23.049334Z","caller":"traceutil/trace.go:171","msg":"trace[1662677166] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"225.774115ms","start":"2026-05-28T00:38:22.823536Z","end":"2026-05-28T00:38:23.04931Z","steps":["trace[1662677166] 'process raft request' (duration: 151.798758ms)","trace[1662677166] 'compare' (duration: 73.537586ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:23.049496Z","caller":"traceutil/trace.go:171","msg":"trace[915192615] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"224.959454ms","start":"2026-05-28T00:38:22.824497Z","end":"2026-05-28T00:38:23.049456Z","steps":["trace[915192615] 'process raft request' (duration: 224.493903ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:23.366457Z","caller":"traceutil/trace.go:171","msg":"trace[438091446] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"312.140868ms","start":"2026-05-28T00:38:23.054282Z","end":"2026-05-28T00:38:23.366423Z","steps":["trace[438091446] 'process raft request' (duration: 240.049191ms)","trace[438091446] 'compare' (duration: 71.83021ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:23.366646Z","caller":"traceutil/trace.go:171","msg":"trace[503987191] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"312.23195ms","start":"2026-05-28T00:38:23.054389Z","end":"2026-05-28T00:38:23.366621Z","steps":["trace[503987191] 'process raft request' (duration: 311.909713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:23.36719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:23.054364Z","time spent":"312.349633ms","remote":"127.0.0.1:49762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:38:23.36719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:23.054265Z","time spent":"312.289642ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:38:23.640985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.54487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-28T00:38:23.64109Z","caller":"traceutil/trace.go:171","msg":"trace[1397226152] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:86; }","duration":"250.554533ms","start":"2026-05-28T00:38:23.39052Z","end":"2026-05-28T00:38:23.641075Z","steps":["trace[1397226152] 'read index received' (duration: 59.814973ms)","trace[1397226152] 'applied index is now lower than readState.Index' (duration: 190.73844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:23.641206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.701517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:23.641239Z","caller":"traceutil/trace.go:171","msg":"trace[707960018] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:83; }","duration":"250.764668ms","start":"2026-05-28T00:38:23.390466Z","end":"2026-05-28T00:38:23.64123Z","steps":["trace[707960018] 'agreement among raft nodes before linearized reading' (duration: 250.671646ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:23.641268Z","caller":"traceutil/trace.go:171","msg":"trace[2084988260] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"269.424826ms","start":"2026-05-28T00:38:23.371705Z","end":"2026-05-28T00:38:23.64113Z","steps":["trace[2084988260] 'process raft request' (duration: 135.445906ms)","trace[2084988260] 'compare' (duration: 133.402656ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:23.673053Z","caller":"traceutil/trace.go:171","msg":"trace[650979856] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"218.29823ms","start":"2026-05-28T00:38:23.454729Z","end":"2026-05-28T00:38:23.673027Z","steps":["trace[650979856] 'process raft request' (duration: 218.130436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:23.673256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.252484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:23.673449Z","caller":"traceutil/trace.go:171","msg":"trace[762844052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"214.482619ms","start":"2026-05-28T00:38:23.458853Z","end":"2026-05-28T00:38:23.673336Z","steps":["trace[762844052] 'agreement among raft nodes before linearized reading' (duration: 214.145411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:23.673768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.650832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:23.674127Z","caller":"traceutil/trace.go:171","msg":"trace[157330618] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:84; }","duration":"282.755155ms","start":"2026-05-28T00:38:23.391099Z","end":"2026-05-28T00:38:23.673854Z","steps":["trace[157330618] 'agreement among raft nodes before linearized reading' (duration: 282.618862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:23.8942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.152143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-28T00:38:23.894473Z","caller":"traceutil/trace.go:171","msg":"trace[153342632] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"152.556095ms","start":"2026-05-28T00:38:23.741898Z","end":"2026-05-28T00:38:23.894454Z","steps":["trace[153342632] 'process raft request' (duration: 152.394061ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:23.894597Z","caller":"traceutil/trace.go:171","msg":"trace[803115902] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"216.361134ms","start":"2026-05-28T00:38:23.678176Z","end":"2026-05-28T00:38:23.894537Z","steps":["trace[803115902] 'process raft request' (duration: 114.796911ms)","trace[803115902] 'compare' (duration: 101.066521ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:23.894674Z","caller":"traceutil/trace.go:171","msg":"trace[731385041] linearizableReadLoop","detail":"{readStateIndex:91; appliedIndex:90; }","duration":"155.057815ms","start":"2026-05-28T00:38:23.739508Z","end":"2026-05-28T00:38:23.894566Z","steps":["trace[731385041] 'read index received' (duration: 53.42998ms)","trace[731385041] 'applied index is now lower than readState.Index' (duration: 101.623955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:23.894906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.035739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:23.894979Z","caller":"traceutil/trace.go:171","msg":"trace[815173090] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:87; }","duration":"217.122231ms","start":"2026-05-28T00:38:23.677845Z","end":"2026-05-28T00:38:23.894967Z","steps":["trace[815173090] 'agreement among raft nodes before linearized reading' (duration: 216.977108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:23.894962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.872462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-28T00:38:23.89491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.622205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:23.89518Z","caller":"traceutil/trace.go:171","msg":"trace[537954037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:87; }","duration":"220.898332ms","start":"2026-05-28T00:38:23.674262Z","end":"2026-05-28T00:38:23.89516Z","steps":["trace[537954037] 'agreement among raft nodes before linearized reading' (duration: 220.587205ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:23.895096Z","caller":"traceutil/trace.go:171","msg":"trace[1054467882] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:87; }","duration":"153.024196ms","start":"2026-05-28T00:38:23.742056Z","end":"2026-05-28T00:38:23.895081Z","steps":["trace[1054467882] 'agreement among raft nodes before linearized reading' (duration: 152.886843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:24.160372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.599377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-28T00:38:24.160562Z","caller":"traceutil/trace.go:171","msg":"trace[1646712920] linearizableReadLoop","detail":"{readStateIndex:99; appliedIndex:98; }","duration":"165.384511ms","start":"2026-05-28T00:38:23.995156Z","end":"2026-05-28T00:38:24.160541Z","steps":["trace[1646712920] 'read index received' (duration: 4.488018ms)","trace[1646712920] 'applied index is now lower than readState.Index' (duration: 160.894713ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:24.160589Z","caller":"traceutil/trace.go:171","msg":"trace[2144636264] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"168.339672ms","start":"2026-05-28T00:38:23.992229Z","end":"2026-05-28T00:38:24.160569Z","steps":["trace[2144636264] 'compare' (duration: 160.481904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:24.160682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.544795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:24.160727Z","caller":"traceutil/trace.go:171","msg":"trace[315151960] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:94; }","duration":"165.617597ms","start":"2026-05-28T00:38:23.99509Z","end":"2026-05-28T00:38:24.160708Z","steps":["trace[315151960] 'agreement among raft nodes before linearized reading' (duration: 165.510425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:24.708885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.059115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-28T00:38:24.709114Z","caller":"traceutil/trace.go:171","msg":"trace[2039347187] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:115; }","duration":"150.091115ms","start":"2026-05-28T00:38:24.558948Z","end":"2026-05-28T00:38:24.709039Z","steps":["trace[2039347187] 'read index received' (duration: 9.789264ms)","trace[2039347187] 'applied index is now lower than readState.Index' (duration: 140.300941ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:24.709301Z","caller":"traceutil/trace.go:171","msg":"trace[866942468] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"201.513609ms","start":"2026-05-28T00:38:24.507765Z","end":"2026-05-28T00:38:24.709279Z","steps":["trace[866942468] 'process raft request' (duration: 201.221022ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:24.709268Z","caller":"traceutil/trace.go:171","msg":"trace[2109253116] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"201.991381ms","start":"2026-05-28T00:38:24.507152Z","end":"2026-05-28T00:38:24.709144Z","steps":["trace[2109253116] 'process raft request' (duration: 61.577907ms)","trace[2109253116] 'compare' (duration: 139.885611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:24.709607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.656429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T00:38:24.70964Z","caller":"traceutil/trace.go:171","msg":"trace[2027212435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"150.726771ms","start":"2026-05-28T00:38:24.558904Z","end":"2026-05-28T00:38:24.709631Z","steps":["trace[2027212435] 'agreement among raft nodes before linearized reading' (duration: 150.673459ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:24.87918Z","caller":"traceutil/trace.go:171","msg":"trace[1036536579] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"166.077415ms","start":"2026-05-28T00:38:24.713072Z","end":"2026-05-28T00:38:24.879149Z","steps":["trace[1036536579] 'process raft request' (duration: 134.409471ms)","trace[1036536579] 'compare' (duration: 31.551601ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:24.880176Z","caller":"traceutil/trace.go:171","msg":"trace[1878091374] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"164.965849ms","start":"2026-05-28T00:38:24.71518Z","end":"2026-05-28T00:38:24.880146Z","steps":["trace[1878091374] 'process raft request' (duration: 164.908138ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:24.880257Z","caller":"traceutil/trace.go:171","msg":"trace[845080033] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"166.801512ms","start":"2026-05-28T00:38:24.713447Z","end":"2026-05-28T00:38:24.880249Z","steps":["trace[845080033] 'process raft request' (duration: 166.425154ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:24.88018Z","caller":"traceutil/trace.go:171","msg":"trace[1183727808] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"166.69176ms","start":"2026-05-28T00:38:24.713463Z","end":"2026-05-28T00:38:24.880155Z","steps":["trace[1183727808] 'process raft request' (duration: 166.546357ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:25.055328Z","caller":"traceutil/trace.go:171","msg":"trace[1793094915] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"103.262522ms","start":"2026-05-28T00:38:24.952046Z","end":"2026-05-28T00:38:25.055308Z","steps":["trace[1793094915] 'process raft request' (duration: 103.218571ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:25.055429Z","caller":"traceutil/trace.go:171","msg":"trace[911077802] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"120.623686ms","start":"2026-05-28T00:38:24.93477Z","end":"2026-05-28T00:38:25.055394Z","steps":["trace[911077802] 'process raft request' (duration: 108.659593ms)","trace[911077802] 'compare' (duration: 11.706857ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:25.958816Z","caller":"traceutil/trace.go:171","msg":"trace[1398057996] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:131; }","duration":"374.445407ms","start":"2026-05-28T00:38:25.584347Z","end":"2026-05-28T00:38:25.958792Z","steps":["trace[1398057996] 'read index received' (duration: 374.325944ms)","trace[1398057996] 'applied index is now lower than readState.Index' (duration: 118.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:25.959081Z","caller":"traceutil/trace.go:171","msg":"trace[1056175320] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"811.109385ms","start":"2026-05-28T00:38:25.147799Z","end":"2026-05-28T00:38:25.958908Z","steps":["trace[1056175320] 'process raft request' (duration: 810.918071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:25.959104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.906843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:25.959181Z","caller":"traceutil/trace.go:171","msg":"trace[1139623113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:128; }","duration":"200.031597ms","start":"2026-05-28T00:38:25.759133Z","end":"2026-05-28T00:38:25.959164Z","steps":["trace[1139623113] 'agreement among raft nodes before linearized reading' (duration: 199.862683ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:25.95903Z","caller":"traceutil/trace.go:171","msg":"trace[1610995334] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"811.573067ms","start":"2026-05-28T00:38:25.147407Z","end":"2026-05-28T00:38:25.95898Z","steps":["trace[1610995334] 'process raft request' (duration: 811.098886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:25.959477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:25.147391Z","time spent":"811.996187ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:25.959636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:25.147789Z","time spent":"811.714161ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:25.959111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.722424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:25.959862Z","caller":"traceutil/trace.go:171","msg":"trace[739213263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:128; }","duration":"375.537142ms","start":"2026-05-28T00:38:25.584299Z","end":"2026-05-28T00:38:25.959836Z","steps":["trace[739213263] 'agreement among raft nodes before linearized reading' (duration: 374.665932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:25.959957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:25.58428Z","time spent":"375.661106ms","remote":"127.0.0.1:49264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:38:27.501831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441319262s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:27.50202Z","caller":"traceutil/trace.go:171","msg":"trace[696462112] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:134; }","duration":"843.104606ms","start":"2026-05-28T00:38:26.658899Z","end":"2026-05-28T00:38:27.502004Z","steps":["trace[696462112] 'read index received' (duration: 44.811µs)","trace[696462112] 'applied index is now lower than readState.Index' (duration: 843.058705ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:27.502209Z","caller":"traceutil/trace.go:171","msg":"trace[271864119] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"1.536754984s","start":"2026-05-28T00:38:25.965437Z","end":"2026-05-28T00:38:27.502192Z","steps":["trace[271864119] 'process raft request' (duration: 94.989652ms)","trace[271864119] 'compare' (duration: 1.439611432s)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:27.502233Z","caller":"traceutil/trace.go:171","msg":"trace[1878523660] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"1.496982743s","start":"2026-05-28T00:38:26.005225Z","end":"2026-05-28T00:38:27.502208Z","steps":["trace[1878523660] 'process raft request' (duration: 1.496710786s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:27.502274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:25.965429Z","time spent":"1.536816976s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":620,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:27.502261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.351211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:27.502339Z","caller":"traceutil/trace.go:171","msg":"trace[821971856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:131; }","duration":"843.438993ms","start":"2026-05-28T00:38:26.658883Z","end":"2026-05-28T00:38:27.502322Z","steps":["trace[821971856] 'agreement among raft nodes before linearized reading' (duration: 843.271109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:27.502344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.32646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-28T00:38:27.502356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:26.005206Z","time spent":"1.497090455s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:27.502388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:26.658823Z","time spent":"843.555937ms","remote":"127.0.0.1:49264","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-05-28T00:38:27.502396Z","caller":"traceutil/trace.go:171","msg":"trace[19271991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:131; }","duration":"617.463113ms","start":"2026-05-28T00:38:26.884917Z","end":"2026-05-28T00:38:27.50238Z","steps":["trace[19271991] 'agreement among raft nodes before linearized reading' (duration: 617.403291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:27.50247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:26.884893Z","time spent":"617.569146ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:38:27.502285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.36345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T00:38:27.502543Z","caller":"traceutil/trace.go:171","msg":"trace[1868455534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:131; }","duration":"617.724089ms","start":"2026-05-28T00:38:26.884805Z","end":"2026-05-28T00:38:27.502529Z","steps":["trace[1868455534] 'agreement among raft nodes before linearized reading' (duration: 617.405502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:27.502594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:26.884784Z","time spent":"617.79406ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:38:28.006918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311646121289031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:38:29.004494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.324815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:29.004732Z","caller":"traceutil/trace.go:171","msg":"trace[406906865] linearizableReadLoop","detail":"{readStateIndex:139; appliedIndex:136; }","duration":"1.498539942s","start":"2026-05-28T00:38:27.506173Z","end":"2026-05-28T00:38:29.004713Z","steps":["trace[406906865] 'read index received' (duration: 647.94209ms)","trace[406906865] 'applied index is now lower than readState.Index' (duration: 850.596892ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:29.004757Z","caller":"traceutil/trace.go:171","msg":"trace[1953185768] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"1.498997083s","start":"2026-05-28T00:38:27.505708Z","end":"2026-05-28T00:38:29.004705Z","steps":["trace[1953185768] 'process raft request' (duration: 648.390941ms)","trace[1953185768] 'compare' (duration: 850.189212ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:29.004853Z","caller":"traceutil/trace.go:171","msg":"trace[596852686] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"1.49753168s","start":"2026-05-28T00:38:27.50728Z","end":"2026-05-28T00:38:29.004812Z","steps":["trace[596852686] 'process raft request' (duration: 1.497401776s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:29.004935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:27.507267Z","time spent":"1.497620281s","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:38:29.004966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:27.505686Z","time spent":"1.499205528s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:29.005017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49883212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:29.0051Z","caller":"traceutil/trace.go:171","msg":"trace[462276288] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:0; response_revision:134; }","duration":"1.498957392s","start":"2026-05-28T00:38:27.506129Z","end":"2026-05-28T00:38:29.005087Z","steps":["trace[462276288] 'agreement among raft nodes before linearized reading' (duration: 1.498679625s)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:29.004796Z","caller":"traceutil/trace.go:171","msg":"trace[705225234] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"1.498642385s","start":"2026-05-28T00:38:27.50613Z","end":"2026-05-28T00:38:29.004772Z","steps":["trace[705225234] 'process raft request' (duration: 1.498476001s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:29.005215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:27.506113Z","time spent":"1.499087685s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:auth-delegator\" "} {"level":"warn","ts":"2026-05-28T00:38:29.005282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:27.506104Z","time spent":"1.499137066s","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:38:29.033264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373913457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:29.033305Z","caller":"traceutil/trace.go:171","msg":"trace[989500861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"1.373985569s","start":"2026-05-28T00:38:27.65931Z","end":"2026-05-28T00:38:29.033295Z","steps":["trace[989500861] 'agreement among raft nodes before linearized reading' (duration: 1.373908147s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:29.033374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:27.659289Z","time spent":"1.374032419s","remote":"127.0.0.1:49264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:38:29.033552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.497931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-05-28T00:38:29.033596Z","caller":"traceutil/trace.go:171","msg":"trace[1506118702] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"598.318294ms","start":"2026-05-28T00:38:28.435265Z","end":"2026-05-28T00:38:29.033583Z","steps":["trace[1506118702] 'process raft request' (duration: 597.865174ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:29.03362Z","caller":"traceutil/trace.go:171","msg":"trace[1310929344] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:135; }","duration":"172.592363ms","start":"2026-05-28T00:38:28.861009Z","end":"2026-05-28T00:38:29.033601Z","steps":["trace[1310929344] 'agreement among raft nodes before linearized reading' (duration: 172.45795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:29.033656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:28.43524Z","time spent":"598.384745ms","remote":"127.0.0.1:49470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:38:29.226123Z","caller":"traceutil/trace.go:171","msg":"trace[1013112214] transaction","detail":"{read_only:false; response_revision:149; number_of_response:1; }","duration":"121.603456ms","start":"2026-05-28T00:38:29.104489Z","end":"2026-05-28T00:38:29.226093Z","steps":["trace[1013112214] 'process raft request' (duration: 76.989563ms)","trace[1013112214] 'compare' (duration: 44.418538ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:29.847878Z","caller":"traceutil/trace.go:171","msg":"trace[1331598132] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"283.830181ms","start":"2026-05-28T00:38:29.564018Z","end":"2026-05-28T00:38:29.847848Z","steps":["trace[1331598132] 'process raft request' (duration: 259.692962ms)","trace[1331598132] 'compare' (duration: 23.994386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:30.11169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.101358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:30.111914Z","caller":"traceutil/trace.go:171","msg":"trace[2075088177] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"213.345635ms","start":"2026-05-28T00:38:29.89854Z","end":"2026-05-28T00:38:30.111886Z","steps":["trace[2075088177] 'process raft request' (duration: 76.983602ms)","trace[2075088177] 'compare' (duration: 135.915223ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:30.345461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.727537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:30.345563Z","caller":"traceutil/trace.go:171","msg":"trace[699578094] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"139.013491ms","start":"2026-05-28T00:38:30.206532Z","end":"2026-05-28T00:38:30.345545Z","steps":["trace[699578094] 'process raft request' (duration: 19.134171ms)","trace[699578094] 'compare' (duration: 119.527622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:30.633758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.614158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:30.633874Z","caller":"traceutil/trace.go:171","msg":"trace[192117998] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"201.131012ms","start":"2026-05-28T00:38:30.432724Z","end":"2026-05-28T00:38:30.633855Z","steps":["trace[192117998] 'process raft request' (duration: 83.34731ms)","trace[192117998] 'compare' (duration: 117.465114ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:30.817863Z","caller":"traceutil/trace.go:171","msg":"trace[2120232313] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"178.93267ms","start":"2026-05-28T00:38:30.638902Z","end":"2026-05-28T00:38:30.817835Z","steps":["trace[2120232313] 'process raft request' (duration: 150.675349ms)","trace[2120232313] 'compare' (duration: 28.096627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:31.164647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.307237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:38:31.164768Z","caller":"traceutil/trace.go:171","msg":"trace[1643869471] linearizableReadLoop","detail":"{readStateIndex:174; appliedIndex:173; }","duration":"155.209024ms","start":"2026-05-28T00:38:31.009538Z","end":"2026-05-28T00:38:31.164747Z","steps":["trace[1643869471] 'read index received' (duration: 23.747104ms)","trace[1643869471] 'applied index is now lower than readState.Index' (duration: 131.46022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:31.164983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.083421ms","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-05-28T00:38:31.165027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.44398ms","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-05-28T00:38:31.165127Z","caller":"traceutil/trace.go:171","msg":"trace[1800524885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:169; }","duration":"155.576983ms","start":"2026-05-28T00:38:31.009492Z","end":"2026-05-28T00:38:31.165069Z","steps":["trace[1800524885] 'agreement among raft nodes before linearized reading' (duration: 155.365618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:31.165326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.157211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:31.16504Z","caller":"traceutil/trace.go:171","msg":"trace[834585069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:169; }","duration":"155.185274ms","start":"2026-05-28T00:38:31.009841Z","end":"2026-05-28T00:38:31.165026Z","steps":["trace[834585069] 'agreement among raft nodes before linearized reading' (duration: 155.03769ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:31.165417Z","caller":"traceutil/trace.go:171","msg":"trace[1570571270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:169; }","duration":"106.268603ms","start":"2026-05-28T00:38:31.059132Z","end":"2026-05-28T00:38:31.165401Z","steps":["trace[1570571270] 'agreement among raft nodes before linearized reading' (duration: 106.158721ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:31.16481Z","caller":"traceutil/trace.go:171","msg":"trace[331160499] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"260.279303ms","start":"2026-05-28T00:38:30.904508Z","end":"2026-05-28T00:38:31.164788Z","steps":["trace[331160499] 'process raft request' (duration: 128.735481ms)","trace[331160499] 'compare' (duration: 131.179304ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:31.302161Z","caller":"traceutil/trace.go:171","msg":"trace[204455338] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"131.917972ms","start":"2026-05-28T00:38:31.170214Z","end":"2026-05-28T00:38:31.302132Z","steps":["trace[204455338] 'process raft request' (duration: 131.799039ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:31.302266Z","caller":"traceutil/trace.go:171","msg":"trace[800163954] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"132.613137ms","start":"2026-05-28T00:38:31.169625Z","end":"2026-05-28T00:38:31.302238Z","steps":["trace[800163954] 'process raft request' (duration: 132.189597ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:31.302382Z","caller":"traceutil/trace.go:171","msg":"trace[993217217] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"131.43261ms","start":"2026-05-28T00:38:31.170929Z","end":"2026-05-28T00:38:31.302361Z","steps":["trace[993217217] 'process raft request' (duration: 131.131723ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:31.302374Z","caller":"traceutil/trace.go:171","msg":"trace[184487367] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"128.288248ms","start":"2026-05-28T00:38:31.174046Z","end":"2026-05-28T00:38:31.302334Z","steps":["trace[184487367] 'process raft request' (duration: 128.061553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:36.552933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.75349ms","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-05-28T00:38:36.553056Z","caller":"traceutil/trace.go:171","msg":"trace[1292592216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"258.889254ms","start":"2026-05-28T00:38:36.294121Z","end":"2026-05-28T00:38:36.55301Z","steps":["trace[1292592216] 'range keys from in-memory index tree' (duration: 258.644188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:36.553041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.711892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-28T00:38:36.553133Z","caller":"traceutil/trace.go:171","msg":"trace[1941259842] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:346; }","duration":"272.845985ms","start":"2026-05-28T00:38:36.280267Z","end":"2026-05-28T00:38:36.553113Z","steps":["trace[1941259842] 'range keys from in-memory index tree' (duration: 272.471587ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.168758Z","caller":"traceutil/trace.go:171","msg":"trace[639079221] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"422.991316ms","start":"2026-05-28T00:38:36.745736Z","end":"2026-05-28T00:38:37.168727Z","steps":["trace[639079221] 'process raft request' (duration: 422.660798ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.168872Z","caller":"traceutil/trace.go:171","msg":"trace[300372402] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"420.784746ms","start":"2026-05-28T00:38:36.748068Z","end":"2026-05-28T00:38:37.168852Z","steps":["trace[300372402] 'process raft request' (duration: 420.4913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.168937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.745724Z","time spent":"423.139759ms","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:37.169008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.748059Z","time spent":"420.865648ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:38:37.213391Z","caller":"traceutil/trace.go:171","msg":"trace[409645645] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:365; }","duration":"433.350441ms","start":"2026-05-28T00:38:36.780019Z","end":"2026-05-28T00:38:37.21337Z","steps":["trace[409645645] 'read index received' (duration: 388.388233ms)","trace[409645645] 'applied index is now lower than readState.Index' (duration: 44.961128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:37.213566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.526145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-28T00:38:37.213601Z","caller":"traceutil/trace.go:171","msg":"trace[568966343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:352; }","duration":"433.606367ms","start":"2026-05-28T00:38:36.779983Z","end":"2026-05-28T00:38:37.213589Z","steps":["trace[568966343] 'agreement among raft nodes before linearized reading' (duration: 433.502384ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.213585Z","caller":"traceutil/trace.go:171","msg":"trace[1655525634] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"439.093449ms","start":"2026-05-28T00:38:36.774453Z","end":"2026-05-28T00:38:37.213546Z","steps":["trace[1655525634] 'process raft request' (duration: 438.816833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.21391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.774427Z","time spent":"439.425267ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:38:37.213735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.779965Z","time spent":"433.75692ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-28T00:38:37.323556Z","caller":"traceutil/trace.go:171","msg":"trace[692710668] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"150.368376ms","start":"2026-05-28T00:38:37.173154Z","end":"2026-05-28T00:38:37.323522Z","steps":["trace[692710668] 'process raft request' (duration: 109.199603ms)","trace[692710668] 'compare' (duration: 40.997189ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:37.323897Z","caller":"traceutil/trace.go:171","msg":"trace[193073290] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"148.472794ms","start":"2026-05-28T00:38:37.175379Z","end":"2026-05-28T00:38:37.323852Z","steps":["trace[193073290] 'process raft request' (duration: 148.104626ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.324015Z","caller":"traceutil/trace.go:171","msg":"trace[2123433419] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"106.881742ms","start":"2026-05-28T00:38:37.217113Z","end":"2026-05-28T00:38:37.323994Z","steps":["trace[2123433419] 'process raft request' (duration: 106.837591ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.324225Z","caller":"traceutil/trace.go:171","msg":"trace[988895592] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:368; }","duration":"110.759397ms","start":"2026-05-28T00:38:37.213455Z","end":"2026-05-28T00:38:37.324214Z","steps":["trace[988895592] 'read index received' (duration: 68.910349ms)","trace[988895592] 'applied index is now lower than readState.Index' (duration: 41.848018ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:37.324201Z","caller":"traceutil/trace.go:171","msg":"trace[1050919530] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"146.174323ms","start":"2026-05-28T00:38:37.177991Z","end":"2026-05-28T00:38:37.324165Z","steps":["trace[1050919530] 'process raft request' (duration: 145.607091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.32455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.984173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-05-28T00:38:37.324582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.601488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-28T00:38:37.324614Z","caller":"traceutil/trace.go:171","msg":"trace[1344610256] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:356; }","duration":"244.201948ms","start":"2026-05-28T00:38:37.080396Z","end":"2026-05-28T00:38:37.324598Z","steps":["trace[1344610256] 'agreement among raft nodes before linearized reading' (duration: 243.870901ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.324734Z","caller":"traceutil/trace.go:171","msg":"trace[1468337326] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:356; }","duration":"344.738441ms","start":"2026-05-28T00:38:36.979926Z","end":"2026-05-28T00:38:37.324664Z","steps":["trace[1468337326] 'agreement among raft nodes before linearized reading' (duration: 344.411514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.324907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.979889Z","time spent":"344.984107ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-28T00:38:37.325019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.283026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-28T00:38:37.325128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.390154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-28T00:38:37.325196Z","caller":"traceutil/trace.go:171","msg":"trace[1624725292] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:356; }","duration":"295.477816ms","start":"2026-05-28T00:38:37.029707Z","end":"2026-05-28T00:38:37.325185Z","steps":["trace[1624725292] 'agreement among raft nodes before linearized reading' (duration: 294.606707ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:37.325291Z","caller":"traceutil/trace.go:171","msg":"trace[1888259114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:356; }","duration":"146.430019ms","start":"2026-05-28T00:38:37.178656Z","end":"2026-05-28T00:38:37.325086Z","steps":["trace[1888259114] 'agreement among raft nodes before linearized reading' (duration: 145.885477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.325446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.923653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-28T00:38:37.325516Z","caller":"traceutil/trace.go:171","msg":"trace[147842508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:356; }","duration":"396.016055ms","start":"2026-05-28T00:38:36.929489Z","end":"2026-05-28T00:38:37.325505Z","steps":["trace[147842508] 'agreement among raft nodes before linearized reading' (duration: 394.862009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.325572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.929477Z","time spent":"396.083277ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-28T00:38:37.325551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.893968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-28T00:38:37.325777Z","caller":"traceutil/trace.go:171","msg":"trace[249861787] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:356; }","duration":"446.111243ms","start":"2026-05-28T00:38:36.879628Z","end":"2026-05-28T00:38:37.325739Z","steps":["trace[249861787] 'agreement among raft nodes before linearized reading' (duration: 444.737723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.325874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.879619Z","time spent":"446.209695ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-05-28T00:38:37.325872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.915307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-28T00:38:37.325924Z","caller":"traceutil/trace.go:171","msg":"trace[591581585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"196.093881ms","start":"2026-05-28T00:38:37.129815Z","end":"2026-05-28T00:38:37.325908Z","steps":["trace[591581585] 'agreement among raft nodes before linearized reading' (duration: 194.598038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.326126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.912246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-28T00:38:37.326199Z","caller":"traceutil/trace.go:171","msg":"trace[944342357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:356; }","duration":"497.061639ms","start":"2026-05-28T00:38:36.829126Z","end":"2026-05-28T00:38:37.326187Z","steps":["trace[944342357] 'agreement among raft nodes before linearized reading' (duration: 495.252009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:37.326232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:36.829108Z","time spent":"497.11436ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-28T00:38:37.741995Z","caller":"traceutil/trace.go:171","msg":"trace[2132286992] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"103.947256ms","start":"2026-05-28T00:38:37.63801Z","end":"2026-05-28T00:38:37.741957Z","steps":["trace[2132286992] 'process raft request' (duration: 69.204786ms)","trace[2132286992] 'compare' (duration: 34.549656ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:38.852427Z","caller":"traceutil/trace.go:171","msg":"trace[373383234] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"239.60994ms","start":"2026-05-28T00:38:38.612792Z","end":"2026-05-28T00:38:38.852402Z","steps":["trace[373383234] 'read index received' (duration: 239.463066ms)","trace[373383234] 'applied index is now lower than readState.Index' (duration: 145.813µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:38.852578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.780464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5316"} {"level":"info","ts":"2026-05-28T00:38:38.85257Z","caller":"traceutil/trace.go:171","msg":"trace[434820787] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"246.38685ms","start":"2026-05-28T00:38:38.606151Z","end":"2026-05-28T00:38:38.852537Z","steps":["trace[434820787] 'process raft request' (duration: 246.080533ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:38.852623Z","caller":"traceutil/trace.go:171","msg":"trace[1178637593] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:392; }","duration":"239.874856ms","start":"2026-05-28T00:38:38.612737Z","end":"2026-05-28T00:38:38.852612Z","steps":["trace[1178637593] 'agreement among raft nodes before linearized reading' (duration: 239.764634ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:39.200519Z","caller":"traceutil/trace.go:171","msg":"trace[93466330] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"339.842671ms","start":"2026-05-28T00:38:38.860647Z","end":"2026-05-28T00:38:39.200489Z","steps":["trace[93466330] 'process raft request' (duration: 339.592926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:39.200738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:38:38.860626Z","time spent":"339.988934ms","remote":"127.0.0.1:49470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5129,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:38:47.030305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.606485ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T00:38:47.030413Z","caller":"traceutil/trace.go:171","msg":"trace[413545977] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"147.593332ms","start":"2026-05-28T00:38:46.882803Z","end":"2026-05-28T00:38:47.030397Z","steps":["trace[413545977] 'read index received' (duration: 56.061µs)","trace[413545977] 'applied index is now lower than readState.Index' (duration: 147.535961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:47.030581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.749006ms","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-05-28T00:38:47.03062Z","caller":"traceutil/trace.go:171","msg":"trace[1974643335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"147.865728ms","start":"2026-05-28T00:38:46.882743Z","end":"2026-05-28T00:38:47.030609Z","steps":["trace[1974643335] 'agreement among raft nodes before linearized reading' (duration: 147.698035ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:47.669776Z","caller":"traceutil/trace.go:171","msg":"trace[205449370] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"259.67515ms","start":"2026-05-28T00:38:47.410083Z","end":"2026-05-28T00:38:47.669758Z","steps":["trace[205449370] 'read index received' (duration: 259.539427ms)","trace[205449370] 'applied index is now lower than readState.Index' (duration: 134.993µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:38:47.669904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.802743ms","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-05-28T00:38:47.669925Z","caller":"traceutil/trace.go:171","msg":"trace[1071371954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"259.857394ms","start":"2026-05-28T00:38:47.410059Z","end":"2026-05-28T00:38:47.669917Z","steps":["trace[1071371954] 'agreement among raft nodes before linearized reading' (duration: 259.803833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:47.669946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.646351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:47.670027Z","caller":"traceutil/trace.go:171","msg":"trace[78102001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"129.772064ms","start":"2026-05-28T00:38:47.540241Z","end":"2026-05-28T00:38:47.670013Z","steps":["trace[78102001] 'agreement among raft nodes before linearized reading' (duration: 129.641252ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:47.670382Z","caller":"traceutil/trace.go:171","msg":"trace[740592455] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"261.719823ms","start":"2026-05-28T00:38:47.408105Z","end":"2026-05-28T00:38:47.669824Z","steps":["trace[740592455] 'process raft request' (duration: 261.54531ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:50.329391Z","caller":"traceutil/trace.go:171","msg":"trace[258645015] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"160.393306ms","start":"2026-05-28T00:38:50.168977Z","end":"2026-05-28T00:38:50.32937Z","steps":["trace[258645015] 'process raft request' (duration: 160.298354ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:53.122843Z","caller":"traceutil/trace.go:171","msg":"trace[862750298] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"145.442007ms","start":"2026-05-28T00:38:52.977379Z","end":"2026-05-28T00:38:53.122821Z","steps":["trace[862750298] 'process raft request' (duration: 145.303924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:54.676861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.145584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-28T00:38:54.676925Z","caller":"traceutil/trace.go:171","msg":"trace[947028489] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:451; }","duration":"161.249075ms","start":"2026-05-28T00:38:54.515661Z","end":"2026-05-28T00:38:54.67691Z","steps":["trace[947028489] 'range keys from in-memory index tree' (duration: 161.029191ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:59.99388Z","caller":"traceutil/trace.go:171","msg":"trace[328934640] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"226.357517ms","start":"2026-05-28T00:38:59.767499Z","end":"2026-05-28T00:38:59.993856Z","steps":["trace[328934640] 'read index received' (duration: 226.190033ms)","trace[328934640] 'applied index is now lower than readState.Index' (duration: 166.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:59.99394Z","caller":"traceutil/trace.go:171","msg":"trace[1262919268] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"261.83463ms","start":"2026-05-28T00:38:59.732081Z","end":"2026-05-28T00:38:59.993915Z","steps":["trace[1262919268] 'process raft request' (duration: 261.644026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:59.994002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.483579ms","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-05-28T00:38:59.994036Z","caller":"traceutil/trace.go:171","msg":"trace[158684689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"226.567821ms","start":"2026-05-28T00:38:59.767456Z","end":"2026-05-28T00:38:59.994024Z","steps":["trace[158684689] 'agreement among raft nodes before linearized reading' (duration: 226.4949ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:09.711899Z","caller":"traceutil/trace.go:171","msg":"trace[2106844867] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"374.923444ms","start":"2026-05-28T00:39:09.336951Z","end":"2026-05-28T00:39:09.711874Z","steps":["trace[2106844867] 'process raft request' (duration: 374.763951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.712031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.336933Z","time spent":"375.040436ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.723283Z","caller":"traceutil/trace.go:171","msg":"trace[1681784796] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"376.493365ms","start":"2026-05-28T00:39:09.346769Z","end":"2026-05-28T00:39:09.723262Z","steps":["trace[1681784796] 'process raft request' (duration: 372.416736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.723874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.346745Z","time spent":"376.591126ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.724258Z","caller":"traceutil/trace.go:171","msg":"trace[139254213] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"364.002353ms","start":"2026-05-28T00:39:09.360175Z","end":"2026-05-28T00:39:09.724178Z","steps":["trace[139254213] 'process raft request' (duration: 363.018904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.724327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.36015Z","time spent":"364.142566ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.724777Z","caller":"traceutil/trace.go:171","msg":"trace[1122634160] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"355.472988ms","start":"2026-05-28T00:39:09.36916Z","end":"2026-05-28T00:39:09.724633Z","steps":["trace[1122634160] 'process raft request' (duration: 354.074671ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:09.724748Z","caller":"traceutil/trace.go:171","msg":"trace[576805244] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"375.876563ms","start":"2026-05-28T00:39:09.348729Z","end":"2026-05-28T00:39:09.724606Z","steps":["trace[576805244] 'process raft request' (duration: 374.431435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.724867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.369144Z","time spent":"355.693582ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:39:09.72506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.348721Z","time spent":"376.133048ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.72535Z","caller":"traceutil/trace.go:171","msg":"trace[287819645] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"344.921314ms","start":"2026-05-28T00:39:09.380367Z","end":"2026-05-28T00:39:09.725288Z","steps":["trace[287819645] 'process raft request' (duration: 342.989696ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:09.725388Z","caller":"traceutil/trace.go:171","msg":"trace[124433420] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"347.360121ms","start":"2026-05-28T00:39:09.377636Z","end":"2026-05-28T00:39:09.724996Z","steps":["trace[124433420] 'process raft request' (duration: 345.648508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.725428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.380343Z","time spent":"345.043996ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:39:09.725602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.37761Z","time spent":"347.948582ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.726038Z","caller":"traceutil/trace.go:171","msg":"trace[451951751] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"348.228838ms","start":"2026-05-28T00:39:09.377778Z","end":"2026-05-28T00:39:09.726007Z","steps":["trace[451951751] 'process raft request' (duration: 345.542946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.72637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.377758Z","time spent":"348.467502ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.727791Z","caller":"traceutil/trace.go:171","msg":"trace[148795419] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"338.540591ms","start":"2026-05-28T00:39:09.389223Z","end":"2026-05-28T00:39:09.727764Z","steps":["trace[148795419] 'process raft request' (duration: 335.40041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.727972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.389209Z","time spent":"338.698193ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.732226Z","caller":"traceutil/trace.go:171","msg":"trace[1784727581] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"340.624151ms","start":"2026-05-28T00:39:09.391584Z","end":"2026-05-28T00:39:09.732208Z","steps":["trace[1784727581] 'process raft request' (duration: 336.153554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.732372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.391573Z","time spent":"340.706952ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:09.733554Z","caller":"traceutil/trace.go:171","msg":"trace[312901081] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"233.366695ms","start":"2026-05-28T00:39:09.50017Z","end":"2026-05-28T00:39:09.733536Z","steps":["trace[312901081] 'process raft request' (duration: 232.254754ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:09.733675Z","caller":"traceutil/trace.go:171","msg":"trace[843106752] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"385.011429ms","start":"2026-05-28T00:39:09.348627Z","end":"2026-05-28T00:39:09.733638Z","steps":["trace[843106752] 'read index received' (duration: 363.153326ms)","trace[843106752] 'applied index is now lower than readState.Index' (duration: 21.856383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:39:09.733781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.144512ms","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-05-28T00:39:09.733818Z","caller":"traceutil/trace.go:171","msg":"trace[173743640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"385.206773ms","start":"2026-05-28T00:39:09.348602Z","end":"2026-05-28T00:39:09.733808Z","steps":["trace[173743640] 'agreement among raft nodes before linearized reading' (duration: 385.118542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.733849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.348589Z","time spent":"385.252224ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T00:39:09.734521Z","caller":"traceutil/trace.go:171","msg":"trace[82739807] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"326.553968ms","start":"2026-05-28T00:39:09.405887Z","end":"2026-05-28T00:39:09.732441Z","steps":["trace[82739807] 'process raft request' (duration: 325.565619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:09.73464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:09.405874Z","time spent":"328.69689ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:39:09.7441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.941396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:39:09.744178Z","caller":"traceutil/trace.go:171","msg":"trace[766117474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"204.047398ms","start":"2026-05-28T00:39:09.540094Z","end":"2026-05-28T00:39:09.744142Z","steps":["trace[766117474] 'agreement among raft nodes before linearized reading' (duration: 203.944116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:15.907056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.389236ms","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-05-28T00:39:15.907137Z","caller":"traceutil/trace.go:171","msg":"trace[812999347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"126.507358ms","start":"2026-05-28T00:39:15.780612Z","end":"2026-05-28T00:39:15.907119Z","steps":["trace[812999347] 'range keys from in-memory index tree' (duration: 126.024349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:15.907365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.447913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nzb5n\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-05-28T00:39:15.907404Z","caller":"traceutil/trace.go:171","msg":"trace[1872031881] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nzb5n; range_end:; response_count:1; response_revision:655; }","duration":"187.509994ms","start":"2026-05-28T00:39:15.719884Z","end":"2026-05-28T00:39:15.907394Z","steps":["trace[1872031881] 'range keys from in-memory index tree' (duration: 186.76605ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:16.068696Z","caller":"traceutil/trace.go:171","msg":"trace[1033905069] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"152.433575ms","start":"2026-05-28T00:39:15.916194Z","end":"2026-05-28T00:39:16.068628Z","steps":["trace[1033905069] 'process raft request' (duration: 152.16481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:16.600523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311646121290100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T00:39:16.658241Z","caller":"traceutil/trace.go:171","msg":"trace[1098615397] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"557.650866ms","start":"2026-05-28T00:39:16.099985Z","end":"2026-05-28T00:39:16.657636Z","steps":["trace[1098615397] 'read index received' (duration: 516.630781ms)","trace[1098615397] 'applied index is now lower than readState.Index' (duration: 41.018985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:39:16.658313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.306609ms","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-05-28T00:39:16.658378Z","caller":"traceutil/trace.go:171","msg":"trace[935467432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"558.409961ms","start":"2026-05-28T00:39:16.09995Z","end":"2026-05-28T00:39:16.65836Z","steps":["trace[935467432] 'agreement among raft nodes before linearized reading' (duration: 558.201017ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:16.658388Z","caller":"traceutil/trace.go:171","msg":"trace[915004796] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"674.945544ms","start":"2026-05-28T00:39:15.983102Z","end":"2026-05-28T00:39:16.658047Z","steps":["trace[915004796] 'process raft request' (duration: 633.742706ms)","trace[915004796] 'compare' (duration: 40.391513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:39:16.658413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:16.099935Z","time spent":"558.471372ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:39:16.658516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:15.983069Z","time spent":"675.357542ms","remote":"127.0.0.1:49386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:39:16.658644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.697664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:39:16.658736Z","caller":"traceutil/trace.go:171","msg":"trace[1746485849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"117.817916ms","start":"2026-05-28T00:39:16.540894Z","end":"2026-05-28T00:39:16.658712Z","steps":["trace[1746485849] 'agreement among raft nodes before linearized reading' (duration: 117.675873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:16.658658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.163992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T00:39:16.659446Z","caller":"traceutil/trace.go:171","msg":"trace[2098389853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"516.992868ms","start":"2026-05-28T00:39:16.142437Z","end":"2026-05-28T00:39:16.65943Z","steps":["trace[2098389853] 'agreement among raft nodes before linearized reading' (duration: 516.066891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:16.659577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:16.142409Z","time spent":"517.152932ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:39:16.658944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.494017ms","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-05-28T00:39:16.659911Z","caller":"traceutil/trace.go:171","msg":"trace[600874273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"111.6599ms","start":"2026-05-28T00:39:16.548216Z","end":"2026-05-28T00:39:16.659876Z","steps":["trace[600874273] 'agreement among raft nodes before linearized reading' (duration: 110.320954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:18.140182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419180249s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-28T00:39:18.207801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.543839ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:39:18.207998Z","caller":"traceutil/trace.go:171","msg":"trace[1424684426] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:661; }","duration":"978.755933ms","start":"2026-05-28T00:39:17.229221Z","end":"2026-05-28T00:39:18.207977Z","steps":["trace[1424684426] 'range keys from in-memory index tree' (duration: 978.528789ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:18.208014Z","caller":"traceutil/trace.go:171","msg":"trace[1848831557] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.487168625s","start":"2026-05-28T00:39:16.720814Z","end":"2026-05-28T00:39:18.207982Z","steps":["trace[1848831557] 'process raft request' (duration: 1.419688318s)","trace[1848831557] 'compare' (duration: 67.325444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:39:18.208304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:16.720788Z","time spent":"1.48742453s","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:39:18.213014Z","caller":"traceutil/trace.go:171","msg":"trace[1698425278] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"290.724933ms","start":"2026-05-28T00:39:17.922274Z","end":"2026-05-28T00:39:18.212999Z","steps":["trace[1698425278] 'read index received' (duration: 218.208692ms)","trace[1698425278] 'applied index is now lower than readState.Index' (duration: 72.515461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:39:18.213293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.003949ms","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-05-28T00:39:18.213359Z","caller":"traceutil/trace.go:171","msg":"trace[1980104419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"291.112081ms","start":"2026-05-28T00:39:17.92223Z","end":"2026-05-28T00:39:18.213342Z","steps":["trace[1980104419] 'agreement among raft nodes before linearized reading' (duration: 290.820035ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:20.059365Z","caller":"traceutil/trace.go:171","msg":"trace[1695942148] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"181.722018ms","start":"2026-05-28T00:39:19.877621Z","end":"2026-05-28T00:39:20.059343Z","steps":["trace[1695942148] 'process raft request' (duration: 181.585166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:33.902584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.244056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:39:33.902688Z","caller":"traceutil/trace.go:171","msg":"trace[1648792959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"361.412499ms","start":"2026-05-28T00:39:33.541251Z","end":"2026-05-28T00:39:33.902664Z","steps":["trace[1648792959] 'range keys from in-memory index tree' (duration: 361.135055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:33.902703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.502391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T00:39:33.902734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:33.541226Z","time spent":"361.498721ms","remote":"127.0.0.1:49264","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-05-28T00:39:33.902768Z","caller":"traceutil/trace.go:171","msg":"trace[175430199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"361.607493ms","start":"2026-05-28T00:39:33.541143Z","end":"2026-05-28T00:39:33.902751Z","steps":["trace[175430199] 'range keys from in-memory index tree' (duration: 361.270007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:33.902813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:39:33.541121Z","time spent":"361.681584ms","remote":"127.0.0.1:49566","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-05-28T00:39:34.06218Z","caller":"traceutil/trace.go:171","msg":"trace[871168443] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"158.311255ms","start":"2026-05-28T00:39:33.90384Z","end":"2026-05-28T00:39:34.062151Z","steps":["trace[871168443] 'read index received' (duration: 158.106481ms)","trace[871168443] 'applied index is now lower than readState.Index' (duration: 203.594µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:39:34.062224Z","caller":"traceutil/trace.go:171","msg":"trace[1790369580] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"226.134445ms","start":"2026-05-28T00:39:33.836055Z","end":"2026-05-28T00:39:34.062189Z","steps":["trace[1790369580] 'process raft request' (duration: 225.89549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:39:34.062348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.488518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:39:34.062394Z","caller":"traceutil/trace.go:171","msg":"trace[1755611184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"158.589419ms","start":"2026-05-28T00:39:33.903793Z","end":"2026-05-28T00:39:34.062382Z","steps":["trace[1755611184] 'agreement among raft nodes before linearized reading' (duration: 158.464117ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:34.074115Z","caller":"traceutil/trace.go:171","msg":"trace[502935355] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"167.123182ms","start":"2026-05-28T00:39:33.906971Z","end":"2026-05-28T00:39:34.074094Z","steps":["trace[502935355] 'process raft request' (duration: 167.052251ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:39:36.997571Z","caller":"traceutil/trace.go:171","msg":"trace[1368223064] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"158.899125ms","start":"2026-05-28T00:39:36.838651Z","end":"2026-05-28T00:39:36.99755Z","steps":["trace[1368223064] 'process raft request' (duration: 158.782422ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:10.368554Z","caller":"traceutil/trace.go:171","msg":"trace[252337554] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"113.802298ms","start":"2026-05-28T00:40:10.254727Z","end":"2026-05-28T00:40:10.368529Z","steps":["trace[252337554] 'process raft request' (duration: 113.652365ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:10.711343Z","caller":"traceutil/trace.go:171","msg":"trace[1027735707] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"114.670462ms","start":"2026-05-28T00:40:10.596638Z","end":"2026-05-28T00:40:10.711309Z","steps":["trace[1027735707] 'process raft request' (duration: 114.54342ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:19.357356Z","caller":"traceutil/trace.go:171","msg":"trace[1991305026] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"231.56884ms","start":"2026-05-28T00:40:19.125767Z","end":"2026-05-28T00:40:19.357336Z","steps":["trace[1991305026] 'read index received' (duration: 231.464458ms)","trace[1991305026] 'applied index is now lower than readState.Index' (duration: 103.352µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:40:19.357472Z","caller":"traceutil/trace.go:171","msg":"trace[678555683] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"241.707337ms","start":"2026-05-28T00:40:19.115749Z","end":"2026-05-28T00:40:19.357456Z","steps":["trace[678555683] 'process raft request' (duration: 241.484393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:40:19.35791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.029868ms","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-05-28T00:40:19.358041Z","caller":"traceutil/trace.go:171","msg":"trace[1273140525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"232.278301ms","start":"2026-05-28T00:40:19.125737Z","end":"2026-05-28T00:40:19.358016Z","steps":["trace[1273140525] 'agreement among raft nodes before linearized reading' (duration: 231.748493ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:19.52041Z","caller":"traceutil/trace.go:171","msg":"trace[2128578401] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"162.95753ms","start":"2026-05-28T00:40:19.357425Z","end":"2026-05-28T00:40:19.520383Z","steps":["trace[2128578401] 'read index received' (duration: 120.40005ms)","trace[2128578401] 'applied index is now lower than readState.Index' (duration: 42.55635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:40:19.520544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.633895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1082"} {"level":"warn","ts":"2026-05-28T00:40:19.52053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.374368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T00:40:19.520584Z","caller":"traceutil/trace.go:171","msg":"trace[793816426] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"340.231977ms","start":"2026-05-28T00:40:19.180325Z","end":"2026-05-28T00:40:19.520557Z","steps":["trace[793816426] 'process raft request' (duration: 297.453933ms)","trace[793816426] 'compare' (duration: 42.474839ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:40:19.520628Z","caller":"traceutil/trace.go:171","msg":"trace[1305938389] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1107; }","duration":"292.735406ms","start":"2026-05-28T00:40:19.227866Z","end":"2026-05-28T00:40:19.520601Z","steps":["trace[1305938389] 'agreement among raft nodes before linearized reading' (duration: 292.607984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:40:19.520642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.792855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T00:40:19.520686Z","caller":"traceutil/trace.go:171","msg":"trace[408773793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"394.888446ms","start":"2026-05-28T00:40:19.125786Z","end":"2026-05-28T00:40:19.520674Z","steps":["trace[408773793] 'agreement among raft nodes before linearized reading' (duration: 394.725273ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:19.520666Z","caller":"traceutil/trace.go:171","msg":"trace[2014004731] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1107; }","duration":"273.564741ms","start":"2026-05-28T00:40:19.247086Z","end":"2026-05-28T00:40:19.520651Z","steps":["trace[2014004731] 'agreement among raft nodes before linearized reading' (duration: 273.336317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:40:19.520798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:40:19.125765Z","time spent":"395.017959ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:40:19.520813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:40:19.180299Z","time spent":"340.403751ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:40:19.521024Z","caller":"traceutil/trace.go:171","msg":"trace[1317068841] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"158.838593ms","start":"2026-05-28T00:40:19.362162Z","end":"2026-05-28T00:40:19.521001Z","steps":["trace[1317068841] 'process raft request' (duration: 158.6449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:40:19.521172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.652242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T00:40:19.521245Z","caller":"traceutil/trace.go:171","msg":"trace[1999489576] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1108; }","duration":"163.766414ms","start":"2026-05-28T00:40:19.357461Z","end":"2026-05-28T00:40:19.521228Z","steps":["trace[1999489576] 'agreement among raft nodes before linearized reading' (duration: 163.567551ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:20.583114Z","caller":"traceutil/trace.go:171","msg":"trace[1305976427] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"117.767445ms","start":"2026-05-28T00:40:20.465314Z","end":"2026-05-28T00:40:20.583081Z","steps":["trace[1305976427] 'process raft request' (duration: 117.550191ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:29.736285Z","caller":"traceutil/trace.go:171","msg":"trace[1083179714] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"124.791747ms","start":"2026-05-28T00:40:29.611456Z","end":"2026-05-28T00:40:29.736248Z","steps":["trace[1083179714] 'process raft request' (duration: 122.791005ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:31.845109Z","caller":"traceutil/trace.go:171","msg":"trace[2050352436] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"101.605816ms","start":"2026-05-28T00:40:31.743479Z","end":"2026-05-28T00:40:31.845085Z","steps":["trace[2050352436] 'process raft request' (duration: 101.445084ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:51.865919Z","caller":"traceutil/trace.go:171","msg":"trace[1410865818] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"183.669007ms","start":"2026-05-28T00:40:51.682228Z","end":"2026-05-28T00:40:51.865897Z","steps":["trace[1410865818] 'process raft request' (duration: 183.541915ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:51.94965Z","caller":"traceutil/trace.go:171","msg":"trace[467806609] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1262; }","duration":"181.165287ms","start":"2026-05-28T00:40:51.768465Z","end":"2026-05-28T00:40:51.94963Z","steps":["trace[467806609] 'read index received' (duration: 97.350686ms)","trace[467806609] 'applied index is now lower than readState.Index' (duration: 83.813851ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:40:51.94972Z","caller":"traceutil/trace.go:171","msg":"trace[715374606] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"207.083379ms","start":"2026-05-28T00:40:51.742593Z","end":"2026-05-28T00:40:51.949677Z","steps":["trace[715374606] 'process raft request' (duration: 162.618093ms)","trace[715374606] 'compare' (duration: 44.328944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:40:51.949829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-28T00:40:51.94986Z","caller":"traceutil/trace.go:171","msg":"trace[1593182603] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1212; }","duration":"181.435231ms","start":"2026-05-28T00:40:51.768413Z","end":"2026-05-28T00:40:51.949848Z","steps":["trace[1593182603] 'agreement among raft nodes before linearized reading' (duration: 181.32159ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:40:52.121154Z","caller":"traceutil/trace.go:171","msg":"trace[1443527520] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"128.353926ms","start":"2026-05-28T00:40:51.992765Z","end":"2026-05-28T00:40:52.121118Z","steps":["trace[1443527520] 'process raft request' (duration: 78.550667ms)","trace[1443527520] 'compare' (duration: 49.566515ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:40:53.240174Z","caller":"traceutil/trace.go:171","msg":"trace[1898580035] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"212.219265ms","start":"2026-05-28T00:40:53.027926Z","end":"2026-05-28T00:40:53.240146Z","steps":["trace[1898580035] 'process raft request' (duration: 212.023572ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:41:18.063746Z","caller":"traceutil/trace.go:171","msg":"trace[454471264] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"108.683023ms","start":"2026-05-28T00:41:17.955033Z","end":"2026-05-28T00:41:18.063716Z","steps":["trace[454471264] 'process raft request' (duration: 108.539611ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:41:18.172163Z","caller":"traceutil/trace.go:171","msg":"trace[1271965661] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"104.121402ms","start":"2026-05-28T00:41:18.068015Z","end":"2026-05-28T00:41:18.172136Z","steps":["trace[1271965661] 'process raft request' (duration: 103.308169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:41:20.380934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.197424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T00:41:20.381025Z","caller":"traceutil/trace.go:171","msg":"trace[1672775326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"205.323665ms","start":"2026-05-28T00:41:20.175672Z","end":"2026-05-28T00:41:20.380995Z","steps":["trace[1672775326] 'range keys from in-memory index tree' (duration: 205.008301ms)"],"step_count":1}