{"level":"warn","ts":"2026-04-28T07:19:08.633349Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:19:08.633457Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.82:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.82:2380","--initial-cluster=instance=https://199.19.213.82:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.82:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.82:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T07:19:08.633538Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:19:08.63355Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-04-28T07:19:08.633571Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:19:08.634528Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"]} {"level":"info","ts":"2026-04-28T07:19:08.634622Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.82:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T07:19:08.652776Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.91334ms"} {"level":"info","ts":"2026-04-28T07:19:08.66576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d464d3ed167a141","cluster-id":"2ab4f6fe2538e5aa"} {"level":"info","ts":"2026-04-28T07:19:08.665867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:19:08.665889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 0"} {"level":"info","ts":"2026-04-28T07:19:08.665909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d464d3ed167a141 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:19:08.665922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 1"} {"level":"info","ts":"2026-04-28T07:19:08.667298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"warn","ts":"2026-04-28T07:19:08.684439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:19:08.691447Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:19:08.692846Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T07:19:08.695713Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d464d3ed167a141","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:19:08.69607Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:19:08.696007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d464d3ed167a141","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:19:08.696152Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:19:08.696173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:19:08.696906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"info","ts":"2026-04-28T07:19:08.702337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","added-peer-id":"5d464d3ed167a141","added-peer-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-04-28T07:19:08.711625Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:19:08.71185Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-04-28T07:19:08.711967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-04-28T07:19:08.712052Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d464d3ed167a141","initial-advertise-peer-urls":["https://199.19.213.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:19:08.712112Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:19:08.770633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:19:08.770685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:19:08.770721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgPreVoteResp from 5d464d3ed167a141 at term 1"} {"level":"info","ts":"2026-04-28T07:19:08.770741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:19:08.770758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgVoteResp from 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-04-28T07:19:08.77078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became leader at term 2"} {"level":"info","ts":"2026-04-28T07:19:08.770799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d464d3ed167a141 elected leader 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-04-28T07:19:08.772119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:19:08.772391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d464d3ed167a141","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.82:2379]}","request-path":"/0/members/5d464d3ed167a141/attributes","cluster-id":"2ab4f6fe2538e5aa","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:19:08.772616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:19:08.772753Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:19:08.772765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:19:08.772821Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:19:08.773875Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:19:08.774038Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:19:08.77407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:19:08.774858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.82:2379"} {"level":"info","ts":"2026-04-28T07:19:08.780362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T07:19:17.275861Z","caller":"traceutil/trace.go:171","msg":"trace[1227960821] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"205.27265ms","start":"2026-04-28T07:19:17.070552Z","end":"2026-04-28T07:19:17.275825Z","steps":["trace[1227960821] 'process raft request' (duration: 205.122673ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:18.895661Z","caller":"traceutil/trace.go:171","msg":"trace[1246383337] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"230.154526ms","start":"2026-04-28T07:19:18.665478Z","end":"2026-04-28T07:19:18.895632Z","steps":["trace[1246383337] 'process raft request' (duration: 229.876873ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:18.902372Z","caller":"traceutil/trace.go:171","msg":"trace[997170191] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"230.44364ms","start":"2026-04-28T07:19:18.6719Z","end":"2026-04-28T07:19:18.902344Z","steps":["trace[997170191] 'process raft request' (duration: 230.183168ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:24.819044Z","caller":"traceutil/trace.go:171","msg":"trace[901096327] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"299.157782ms","start":"2026-04-28T07:19:24.519853Z","end":"2026-04-28T07:19:24.819011Z","steps":["trace[901096327] 'read index received' (duration: 298.863068ms)","trace[901096327] 'applied index is now lower than readState.Index' (duration: 293.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:24.819178Z","caller":"traceutil/trace.go:171","msg":"trace[1528502603] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"414.767215ms","start":"2026-04-28T07:19:24.404365Z","end":"2026-04-28T07:19:24.819132Z","steps":["trace[1528502603] 'process raft request' (duration: 414.444669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:24.819239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.362302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:19:24.819332Z","caller":"traceutil/trace.go:171","msg":"trace[5005625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"299.544921ms","start":"2026-04-28T07:19:24.519769Z","end":"2026-04-28T07:19:24.819314Z","steps":["trace[5005625] 'agreement among raft nodes before linearized reading' (duration: 299.372403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:24.819938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:24.404335Z","time spent":"414.961395ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:25.275909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.138921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:25.276072Z","caller":"traceutil/trace.go:171","msg":"trace[824475801] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"351.919676ms","start":"2026-04-28T07:19:24.924127Z","end":"2026-04-28T07:19:25.276046Z","steps":["trace[824475801] 'read index received' (duration: 195.159495ms)","trace[824475801] 'applied index is now lower than readState.Index' (duration: 156.758001ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:25.27617Z","caller":"traceutil/trace.go:171","msg":"trace[1069249239] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"451.956134ms","start":"2026-04-28T07:19:24.824177Z","end":"2026-04-28T07:19:25.276133Z","steps":["trace[1069249239] 'process raft request' (duration: 295.246015ms)","trace[1069249239] 'compare' (duration: 155.940542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:25.27623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.304416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:19:25.276243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.113725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:19:25.276277Z","caller":"traceutil/trace.go:171","msg":"trace[1338205601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"274.390991ms","start":"2026-04-28T07:19:25.00187Z","end":"2026-04-28T07:19:25.276261Z","steps":["trace[1338205601] 'agreement among raft nodes before linearized reading' (duration: 274.310107ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:25.276306Z","caller":"traceutil/trace.go:171","msg":"trace[1324357707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"352.221101ms","start":"2026-04-28T07:19:24.924067Z","end":"2026-04-28T07:19:25.276288Z","steps":["trace[1324357707] 'agreement among raft nodes before linearized reading' (duration: 352.078524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:25.276334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:24.824156Z","time spent":"452.092091ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:25.276365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:24.924044Z","time spent":"352.306305ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:19:25.276588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.078945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:19:25.276688Z","caller":"traceutil/trace.go:171","msg":"trace[254224262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"273.249814ms","start":"2026-04-28T07:19:25.003414Z","end":"2026-04-28T07:19:25.276664Z","steps":["trace[254224262] 'agreement among raft nodes before linearized reading' (duration: 273.035254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:25.841868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.975374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:25.842756Z","caller":"traceutil/trace.go:171","msg":"trace[427828863] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"561.453253ms","start":"2026-04-28T07:19:25.280636Z","end":"2026-04-28T07:19:25.842089Z","steps":["trace[427828863] 'process raft request' (duration: 291.06668ms)","trace[427828863] 'compare' (duration: 269.679308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:25.842959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:25.28061Z","time spent":"562.243231ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:25.843064Z","caller":"traceutil/trace.go:171","msg":"trace[738628587] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:385; }","duration":"276.179538ms","start":"2026-04-28T07:19:25.566852Z","end":"2026-04-28T07:19:25.843031Z","steps":["trace[738628587] 'read index received' (duration: 4.846039ms)","trace[738628587] 'applied index is now lower than readState.Index' (duration: 271.332299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:25.843268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.394679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4424"} {"level":"info","ts":"2026-04-28T07:19:25.843278Z","caller":"traceutil/trace.go:171","msg":"trace[1815838421] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"562.295125ms","start":"2026-04-28T07:19:25.280968Z","end":"2026-04-28T07:19:25.843263Z","steps":["trace[1815838421] 'process raft request' (duration: 562.018321ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:25.843314Z","caller":"traceutil/trace.go:171","msg":"trace[584801312] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:373; }","duration":"276.516865ms","start":"2026-04-28T07:19:25.566785Z","end":"2026-04-28T07:19:25.843302Z","steps":["trace[584801312] 'agreement among raft nodes before linearized reading' (duration: 276.374587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:25.843342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:25.280936Z","time spent":"562.372499ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:25.843265Z","caller":"traceutil/trace.go:171","msg":"trace[1694272738] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"562.369628ms","start":"2026-04-28T07:19:25.280862Z","end":"2026-04-28T07:19:25.843231Z","steps":["trace[1694272738] 'process raft request' (duration: 561.974458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:25.84351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:25.280842Z","time spent":"562.634512ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:28.00689Z","caller":"traceutil/trace.go:171","msg":"trace[260236751] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"155.354968ms","start":"2026-04-28T07:19:27.851487Z","end":"2026-04-28T07:19:28.006842Z","steps":["trace[260236751] 'process raft request' (duration: 134.765564ms)","trace[260236751] 'compare' (duration: 20.273979ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:28.007354Z","caller":"traceutil/trace.go:171","msg":"trace[153354533] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:425; }","duration":"120.116223ms","start":"2026-04-28T07:19:27.887215Z","end":"2026-04-28T07:19:28.007331Z","steps":["trace[153354533] 'read index received' (duration: 99.074437ms)","trace[153354533] 'applied index is now lower than readState.Index' (duration: 21.040936ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:28.007498Z","caller":"traceutil/trace.go:171","msg":"trace[1219388375] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"155.80323ms","start":"2026-04-28T07:19:27.851681Z","end":"2026-04-28T07:19:28.007484Z","steps":["trace[1219388375] 'process raft request' (duration: 155.589409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:28.007555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.326113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:28.008036Z","caller":"traceutil/trace.go:171","msg":"trace[1124264219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"120.590346ms","start":"2026-04-28T07:19:27.887181Z","end":"2026-04-28T07:19:28.007771Z","steps":["trace[1124264219] 'agreement among raft nodes before linearized reading' (duration: 120.325563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:34.059448Z","caller":"traceutil/trace.go:171","msg":"trace[1297677876] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"100.491655ms","start":"2026-04-28T07:19:33.958917Z","end":"2026-04-28T07:19:34.059408Z","steps":["trace[1297677876] 'process raft request' (duration: 100.308016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:36.73957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.625442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:36.739695Z","caller":"traceutil/trace.go:171","msg":"trace[917097680] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"567.519888ms","start":"2026-04-28T07:19:36.172154Z","end":"2026-04-28T07:19:36.739674Z","steps":["trace[917097680] 'read index received' (duration: 295.676216ms)","trace[917097680] 'applied index is now lower than readState.Index' (duration: 271.842322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:36.73985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.702326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T07:19:36.73988Z","caller":"traceutil/trace.go:171","msg":"trace[868862903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"567.800752ms","start":"2026-04-28T07:19:36.172067Z","end":"2026-04-28T07:19:36.739868Z","steps":["trace[868862903] 'agreement among raft nodes before linearized reading' (duration: 567.657635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:36.739914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:36.172047Z","time spent":"567.858805ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:19:36.739892Z","caller":"traceutil/trace.go:171","msg":"trace[622195939] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"582.868519ms","start":"2026-04-28T07:19:36.156954Z","end":"2026-04-28T07:19:36.739822Z","steps":["trace[622195939] 'process raft request' (duration: 310.868298ms)","trace[622195939] 'compare' (duration: 271.290606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:36.740106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:36.156936Z","time spent":"583.067758ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:39.094765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.448017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:39.094868Z","caller":"traceutil/trace.go:171","msg":"trace[1047457381] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:433; }","duration":"114.628976ms","start":"2026-04-28T07:19:38.980216Z","end":"2026-04-28T07:19:39.094845Z","steps":["trace[1047457381] 'range keys from in-memory index tree' (duration: 114.356653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:39.094837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.996045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:39.094965Z","caller":"traceutil/trace.go:171","msg":"trace[683825491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"206.160863ms","start":"2026-04-28T07:19:38.888777Z","end":"2026-04-28T07:19:39.094938Z","steps":["trace[683825491] 'range keys from in-memory index tree' (duration: 205.88796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:39.412005Z","caller":"traceutil/trace.go:171","msg":"trace[682668753] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"121.092608ms","start":"2026-04-28T07:19:39.290884Z","end":"2026-04-28T07:19:39.411977Z","steps":["trace[682668753] 'process raft request' (duration: 121.052606ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:39.412203Z","caller":"traceutil/trace.go:171","msg":"trace[1314285751] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"121.839755ms","start":"2026-04-28T07:19:39.290222Z","end":"2026-04-28T07:19:39.412061Z","steps":["trace[1314285751] 'process raft request' (duration: 29.796801ms)","trace[1314285751] 'compare' (duration: 91.76305ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:39.646222Z","caller":"traceutil/trace.go:171","msg":"trace[926178895] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"167.821441ms","start":"2026-04-28T07:19:39.478357Z","end":"2026-04-28T07:19:39.646178Z","steps":["trace[926178895] 'process raft request' (duration: 111.527272ms)","trace[926178895] 'compare' (duration: 56.036805ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:39.765857Z","caller":"traceutil/trace.go:171","msg":"trace[1796674147] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"114.876789ms","start":"2026-04-28T07:19:39.650947Z","end":"2026-04-28T07:19:39.765824Z","steps":["trace[1796674147] 'process raft request' (duration: 101.301765ms)","trace[1796674147] 'compare' (duration: 13.361724ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:39.765879Z","caller":"traceutil/trace.go:171","msg":"trace[785116869] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"114.054607ms","start":"2026-04-28T07:19:39.651787Z","end":"2026-04-28T07:19:39.765842Z","steps":["trace[785116869] 'process raft request' (duration: 113.950512ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:39.915872Z","caller":"traceutil/trace.go:171","msg":"trace[1840043205] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"101.877365ms","start":"2026-04-28T07:19:39.813958Z","end":"2026-04-28T07:19:39.915835Z","steps":["trace[1840043205] 'process raft request' (duration: 64.683836ms)","trace[1840043205] 'compare' (duration: 37.049461ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.09484Z","caller":"traceutil/trace.go:171","msg":"trace[1497421473] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"122.383776ms","start":"2026-04-28T07:19:39.972428Z","end":"2026-04-28T07:19:40.094811Z","steps":["trace[1497421473] 'process raft request' (duration: 122.238308ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.158148Z","caller":"traceutil/trace.go:171","msg":"trace[1925176899] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"153.281203ms","start":"2026-04-28T07:19:40.004829Z","end":"2026-04-28T07:19:40.15811Z","steps":["trace[1925176899] 'process raft request' (duration: 152.989918ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.158864Z","caller":"traceutil/trace.go:171","msg":"trace[705308668] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"114.058053ms","start":"2026-04-28T07:19:40.044776Z","end":"2026-04-28T07:19:40.158834Z","steps":["trace[705308668] 'process raft request' (duration: 113.379728ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.34096Z","caller":"traceutil/trace.go:171","msg":"trace[1595717173] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"239.992065ms","start":"2026-04-28T07:19:40.100946Z","end":"2026-04-28T07:19:40.340939Z","steps":["trace[1595717173] 'process raft request' (duration: 239.916861ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.341051Z","caller":"traceutil/trace.go:171","msg":"trace[514098795] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"240.499561ms","start":"2026-04-28T07:19:40.100538Z","end":"2026-04-28T07:19:40.341037Z","steps":["trace[514098795] 'process raft request' (duration: 240.258459ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.340962Z","caller":"traceutil/trace.go:171","msg":"trace[265324665] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"241.598776ms","start":"2026-04-28T07:19:40.099319Z","end":"2026-04-28T07:19:40.340918Z","steps":["trace[265324665] 'process raft request' (duration: 165.391065ms)","trace[265324665] 'compare' (duration: 75.890114ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.343724Z","caller":"traceutil/trace.go:171","msg":"trace[1425817638] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"178.138509ms","start":"2026-04-28T07:19:40.165561Z","end":"2026-04-28T07:19:40.3437Z","steps":["trace[1425817638] 'process raft request' (duration: 177.982552ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.344038Z","caller":"traceutil/trace.go:171","msg":"trace[1375730497] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"177.163991ms","start":"2026-04-28T07:19:40.16685Z","end":"2026-04-28T07:19:40.344014Z","steps":["trace[1375730497] 'process raft request' (duration: 176.805933ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.344273Z","caller":"traceutil/trace.go:171","msg":"trace[2112194372] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"172.084899ms","start":"2026-04-28T07:19:40.172169Z","end":"2026-04-28T07:19:40.344254Z","steps":["trace[2112194372] 'process raft request' (duration: 171.755873ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.344735Z","caller":"traceutil/trace.go:171","msg":"trace[43645835] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:469; }","duration":"166.486131ms","start":"2026-04-28T07:19:40.17823Z","end":"2026-04-28T07:19:40.344716Z","steps":["trace[43645835] 'read index received' (duration: 86.596037ms)","trace[43645835] 'applied index is now lower than readState.Index' (duration: 79.888163ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:40.344918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.664509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T07:19:40.344965Z","caller":"traceutil/trace.go:171","msg":"trace[1621529297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"166.756204ms","start":"2026-04-28T07:19:40.178194Z","end":"2026-04-28T07:19:40.34495Z","steps":["trace[1621529297] 'agreement among raft nodes before linearized reading' (duration: 166.645759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.345962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.506894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-28T07:19:40.346022Z","caller":"traceutil/trace.go:171","msg":"trace[908694088] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:456; }","duration":"116.626569ms","start":"2026-04-28T07:19:40.229376Z","end":"2026-04-28T07:19:40.346003Z","steps":["trace[908694088] 'agreement among raft nodes before linearized reading' (duration: 115.822389ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.580582Z","caller":"traceutil/trace.go:171","msg":"trace[1247372676] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"230.078351ms","start":"2026-04-28T07:19:40.350476Z","end":"2026-04-28T07:19:40.580554Z","steps":["trace[1247372676] 'process raft request' (duration: 229.933374ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.580637Z","caller":"traceutil/trace.go:171","msg":"trace[1637640032] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"231.957205ms","start":"2026-04-28T07:19:40.348647Z","end":"2026-04-28T07:19:40.580604Z","steps":["trace[1637640032] 'process raft request' (duration: 143.336818ms)","trace[1637640032] 'compare' (duration: 88.314632ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.58074Z","caller":"traceutil/trace.go:171","msg":"trace[640471080] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"227.43904ms","start":"2026-04-28T07:19:40.353293Z","end":"2026-04-28T07:19:40.580732Z","steps":["trace[640471080] 'process raft request' (duration: 227.168807ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.581004Z","caller":"traceutil/trace.go:171","msg":"trace[521647928] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"225.700263ms","start":"2026-04-28T07:19:40.355293Z","end":"2026-04-28T07:19:40.580993Z","steps":["trace[521647928] 'process raft request' (duration: 225.193648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.581125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.354205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cbb8r\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-28T07:19:40.581164Z","caller":"traceutil/trace.go:171","msg":"trace[534544356] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cbb8r; range_end:; response_count:1; response_revision:462; }","duration":"230.431219ms","start":"2026-04-28T07:19:40.350722Z","end":"2026-04-28T07:19:40.581153Z","steps":["trace[534544356] 'agreement among raft nodes before linearized reading' (duration: 230.348745ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.58071Z","caller":"traceutil/trace.go:171","msg":"trace[339898809] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:476; }","duration":"229.906423ms","start":"2026-04-28T07:19:40.350746Z","end":"2026-04-28T07:19:40.580653Z","steps":["trace[339898809] 'read index received' (duration: 19.530631ms)","trace[339898809] 'applied index is now lower than readState.Index' (duration: 210.373352ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.581118Z","caller":"traceutil/trace.go:171","msg":"trace[206606543] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"229.183237ms","start":"2026-04-28T07:19:40.351371Z","end":"2026-04-28T07:19:40.580554Z","steps":["trace[206606543] 'process raft request' (duration: 229.067392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.581555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.996814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-28T07:19:40.581607Z","caller":"traceutil/trace.go:171","msg":"trace[234787781] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:462; }","duration":"129.063208ms","start":"2026-04-28T07:19:40.452528Z","end":"2026-04-28T07:19:40.581592Z","steps":["trace[234787781] 'agreement among raft nodes before linearized reading' (duration: 128.784414ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.76628Z","caller":"traceutil/trace.go:171","msg":"trace[779193850] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"168.939971ms","start":"2026-04-28T07:19:40.597305Z","end":"2026-04-28T07:19:40.766245Z","steps":["trace[779193850] 'process raft request' (duration: 168.835186ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:40.766308Z","caller":"traceutil/trace.go:171","msg":"trace[1092422720] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"177.381151ms","start":"2026-04-28T07:19:40.588888Z","end":"2026-04-28T07:19:40.766269Z","steps":["trace[1092422720] 'process raft request' (duration: 127.801926ms)","trace[1092422720] 'compare' (duration: 49.2695ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.947167Z","caller":"traceutil/trace.go:171","msg":"trace[477585989] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"173.086798ms","start":"2026-04-28T07:19:40.774022Z","end":"2026-04-28T07:19:40.947109Z","steps":["trace[477585989] 'process raft request' (duration: 123.857549ms)","trace[477585989] 'compare' (duration: 49.115713ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.991418Z","caller":"traceutil/trace.go:171","msg":"trace[70091030] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"205.90712ms","start":"2026-04-28T07:19:40.785485Z","end":"2026-04-28T07:19:40.991392Z","steps":["trace[70091030] 'read index received' (duration: 112.462872ms)","trace[70091030] 'applied index is now lower than readState.Index' (duration: 93.442768ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:40.99149Z","caller":"traceutil/trace.go:171","msg":"trace[100435874] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"213.988263ms","start":"2026-04-28T07:19:40.777458Z","end":"2026-04-28T07:19:40.991446Z","steps":["trace[100435874] 'process raft request' (duration: 213.759722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.991638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.126562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:19:40.991686Z","caller":"traceutil/trace.go:171","msg":"trace[1392478233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"206.224716ms","start":"2026-04-28T07:19:40.78545Z","end":"2026-04-28T07:19:40.991675Z","steps":["trace[1392478233] 'agreement among raft nodes before linearized reading' (duration: 206.09785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.991728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.551349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:40.991826Z","caller":"traceutil/trace.go:171","msg":"trace[34258578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"104.663165ms","start":"2026-04-28T07:19:40.887139Z","end":"2026-04-28T07:19:40.991802Z","steps":["trace[34258578] 'agreement among raft nodes before linearized reading' (duration: 104.509867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:40.991849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.365793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:19:40.992011Z","caller":"traceutil/trace.go:171","msg":"trace[967557968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"172.628166ms","start":"2026-04-28T07:19:40.819355Z","end":"2026-04-28T07:19:40.991983Z","steps":["trace[967557968] 'agreement among raft nodes before linearized reading' (duration: 172.268918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:41.166574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.832726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:41.16679Z","caller":"traceutil/trace.go:171","msg":"trace[1378067153] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"167.831281ms","start":"2026-04-28T07:19:40.998938Z","end":"2026-04-28T07:19:41.166769Z","steps":["trace[1378067153] 'process raft request' (duration: 167.759338ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:41.166862Z","caller":"traceutil/trace.go:171","msg":"trace[1593120459] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"168.571318ms","start":"2026-04-28T07:19:40.998252Z","end":"2026-04-28T07:19:41.166823Z","steps":["trace[1593120459] 'process raft request' (duration: 65.368373ms)","trace[1593120459] 'compare' (duration: 102.662268ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:42.480899Z","caller":"traceutil/trace.go:171","msg":"trace[2143035115] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"388.746435ms","start":"2026-04-28T07:19:42.092117Z","end":"2026-04-28T07:19:42.480864Z","steps":["trace[2143035115] 'process raft request' (duration: 337.145956ms)","trace[2143035115] 'compare' (duration: 51.302164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:42.481903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.092088Z","time spent":"389.662492ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:42.483799Z","caller":"traceutil/trace.go:171","msg":"trace[61305357] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"323.488086ms","start":"2026-04-28T07:19:42.160233Z","end":"2026-04-28T07:19:42.483721Z","steps":["trace[61305357] 'read index received' (duration: 269.076247ms)","trace[61305357] 'applied index is now lower than readState.Index' (duration: 54.408879ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:42.484685Z","caller":"traceutil/trace.go:171","msg":"trace[1627174638] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"391.368377ms","start":"2026-04-28T07:19:42.093235Z","end":"2026-04-28T07:19:42.484604Z","steps":["trace[1627174638] 'process raft request' (duration: 387.531025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:42.487774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.093218Z","time spent":"394.396058ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:42.484843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.587742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T07:19:42.488338Z","caller":"traceutil/trace.go:171","msg":"trace[1106588078] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:511; }","duration":"328.109207ms","start":"2026-04-28T07:19:42.160181Z","end":"2026-04-28T07:19:42.48829Z","steps":["trace[1106588078] 'agreement among raft nodes before linearized reading' (duration: 323.717019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:42.488582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.160152Z","time spent":"328.353869ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.82\" "} {"level":"info","ts":"2026-04-28T07:19:42.558054Z","caller":"traceutil/trace.go:171","msg":"trace[117165638] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"378.888095ms","start":"2026-04-28T07:19:42.179092Z","end":"2026-04-28T07:19:42.55798Z","steps":["trace[117165638] 'process raft request' (duration: 378.417772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:42.558063Z","caller":"traceutil/trace.go:171","msg":"trace[650433239] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"368.34584ms","start":"2026-04-28T07:19:42.189676Z","end":"2026-04-28T07:19:42.558022Z","steps":["trace[650433239] 'process raft request' (duration: 368.102638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:42.558257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.179064Z","time spent":"379.099505ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:19:42.558532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.18966Z","time spent":"368.655276ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:42.835787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.490185Z","time spent":"345.590607ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T07:19:42.835969Z","caller":"traceutil/trace.go:171","msg":"trace[638960610] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"321.546361ms","start":"2026-04-28T07:19:42.514388Z","end":"2026-04-28T07:19:42.835934Z","steps":["trace[638960610] 'process raft request' (duration: 321.276488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:42.836177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.514356Z","time spent":"321.706258ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:42.837223Z","caller":"traceutil/trace.go:171","msg":"trace[1199784586] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"314.132741ms","start":"2026-04-28T07:19:42.5224Z","end":"2026-04-28T07:19:42.836532Z","steps":["trace[1199784586] 'process raft request' (duration: 313.462678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:42.837345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.522374Z","time spent":"314.911841ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:43.632083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.803248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:43.632241Z","caller":"traceutil/trace.go:171","msg":"trace[523138501] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:534; }","duration":"1.0474023s","start":"2026-04-28T07:19:42.584824Z","end":"2026-04-28T07:19:43.632226Z","steps":["trace[523138501] 'read index received' (duration: 212.631997ms)","trace[523138501] 'applied index is now lower than readState.Index' (duration: 834.769563ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:43.632294Z","caller":"traceutil/trace.go:171","msg":"trace[1310920490] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.06808049s","start":"2026-04-28T07:19:42.564207Z","end":"2026-04-28T07:19:43.632288Z","steps":["trace[1310920490] 'process raft request' (duration: 834.012658ms)","trace[1310920490] 'compare' (duration: 233.667002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:43.632326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.564179Z","time spent":"1.068132622s","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:19:43.632354Z","caller":"traceutil/trace.go:171","msg":"trace[1435211793] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"995.674674ms","start":"2026-04-28T07:19:42.636644Z","end":"2026-04-28T07:19:43.632318Z","steps":["trace[1435211793] 'process raft request' (duration: 995.540528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:43.632592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.636611Z","time spent":"995.875325ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:19:43.632616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047783649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T07:19:43.632774Z","caller":"traceutil/trace.go:171","msg":"trace[323970315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.047936537s","start":"2026-04-28T07:19:42.584776Z","end":"2026-04-28T07:19:43.632712Z","steps":["trace[323970315] 'agreement among raft nodes before linearized reading' (duration: 1.047634671s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:43.632864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.584759Z","time spent":"1.048047282s","remote":"127.0.0.1:40452","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":"info","ts":"2026-04-28T07:19:43.689744Z","caller":"traceutil/trace.go:171","msg":"trace[1929804784] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"852.572541ms","start":"2026-04-28T07:19:42.837122Z","end":"2026-04-28T07:19:43.689695Z","steps":["trace[1929804784] 'process raft request' (duration: 852.331469ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:43.689924Z","caller":"traceutil/trace.go:171","msg":"trace[1436601711] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"832.120213ms","start":"2026-04-28T07:19:42.857751Z","end":"2026-04-28T07:19:43.689872Z","steps":["trace[1436601711] 'process raft request' (duration: 831.835179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:43.690017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.83709Z","time spent":"852.785081ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:43.690165Z","caller":"traceutil/trace.go:171","msg":"trace[1503066306] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"817.220851ms","start":"2026-04-28T07:19:42.872916Z","end":"2026-04-28T07:19:43.690137Z","steps":["trace[1503066306] 'process raft request' (duration: 816.743517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:43.69026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.181396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T07:19:43.690392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.687727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:19:43.690395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.531688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:19:43.690442Z","caller":"traceutil/trace.go:171","msg":"trace[837221816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"516.44072ms","start":"2026-04-28T07:19:43.17397Z","end":"2026-04-28T07:19:43.690411Z","steps":["trace[837221816] 'agreement among raft nodes before linearized reading' (duration: 516.010678ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:43.690457Z","caller":"traceutil/trace.go:171","msg":"trace[2029177603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"802.807532ms","start":"2026-04-28T07:19:42.887632Z","end":"2026-04-28T07:19:43.690439Z","steps":["trace[2029177603] 'agreement among raft nodes before linearized reading' (duration: 802.151519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:43.690481Z","caller":"traceutil/trace.go:171","msg":"trace[1859888853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"661.668415ms","start":"2026-04-28T07:19:43.02879Z","end":"2026-04-28T07:19:43.690458Z","steps":["trace[1859888853] 'agreement among raft nodes before linearized reading' (duration: 661.321118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:43.690271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.857705Z","time spent":"832.398226ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:43.690509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.887582Z","time spent":"802.915008ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:19:43.690534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.028761Z","time spent":"661.759819ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:19:43.690572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.173946Z","time spent":"516.549795ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:19:43.690791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:42.872888Z","time spent":"817.478343ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:44.323163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.425555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:44.323493Z","caller":"traceutil/trace.go:171","msg":"trace[1234704402] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"629.532643ms","start":"2026-04-28T07:19:43.693933Z","end":"2026-04-28T07:19:44.323465Z","steps":["trace[1234704402] 'read index received' (duration: 457.784392ms)","trace[1234704402] 'applied index is now lower than readState.Index' (duration: 171.746841ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:44.323602Z","caller":"traceutil/trace.go:171","msg":"trace[643835132] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"644.662657ms","start":"2026-04-28T07:19:43.678925Z","end":"2026-04-28T07:19:44.323588Z","steps":["trace[643835132] 'process raft request' (duration: 644.431856ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:44.323849Z","caller":"traceutil/trace.go:171","msg":"trace[2041880471] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"688.177074ms","start":"2026-04-28T07:19:43.635627Z","end":"2026-04-28T07:19:44.323804Z","steps":["trace[2041880471] 'process raft request' (duration: 515.982681ms)","trace[2041880471] 'compare' (duration: 171.193443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:44.323959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.017108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-04-28T07:19:44.324019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.635608Z","time spent":"688.318751ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:44.324026Z","caller":"traceutil/trace.go:171","msg":"trace[128163824] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:522; }","duration":"630.154334ms","start":"2026-04-28T07:19:43.693855Z","end":"2026-04-28T07:19:44.32401Z","steps":["trace[128163824] 'agreement among raft nodes before linearized reading' (duration: 629.988636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.324089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.693785Z","time spent":"630.291271ms","remote":"127.0.0.1:40354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T07:19:44.324561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.678899Z","time spent":"644.748791ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:19:44.640524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.785618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:44.64084Z","caller":"traceutil/trace.go:171","msg":"trace[543331592] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"945.745505ms","start":"2026-04-28T07:19:43.695051Z","end":"2026-04-28T07:19:44.640797Z","steps":["trace[543331592] 'process raft request' (duration: 806.590349ms)","trace[543331592] 'compare' (duration: 138.635431ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:44.640966Z","caller":"traceutil/trace.go:171","msg":"trace[335909264] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"945.568966ms","start":"2026-04-28T07:19:43.695367Z","end":"2026-04-28T07:19:44.640936Z","steps":["trace[335909264] 'process raft request' (duration: 945.276862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.641102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.695028Z","time spent":"945.904653ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:44.641111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.695355Z","time spent":"945.676813ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:44.641362Z","caller":"traceutil/trace.go:171","msg":"trace[1213370637] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"922.919559ms","start":"2026-04-28T07:19:43.718417Z","end":"2026-04-28T07:19:44.641337Z","steps":["trace[1213370637] 'process raft request' (duration: 922.440764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.641575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.718393Z","time spent":"923.028934ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:44.641575Z","caller":"traceutil/trace.go:171","msg":"trace[855836658] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"919.174522ms","start":"2026-04-28T07:19:43.722362Z","end":"2026-04-28T07:19:44.641536Z","steps":["trace[855836658] 'process raft request' (duration: 918.829495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.641617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.276357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:44.641671Z","caller":"traceutil/trace.go:171","msg":"trace[1439174744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"753.391713ms","start":"2026-04-28T07:19:43.888266Z","end":"2026-04-28T07:19:44.641657Z","steps":["trace[1439174744] 'agreement among raft nodes before linearized reading' (duration: 753.277787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.641711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.888226Z","time spent":"753.477737ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:19:44.641778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.722333Z","time spent":"919.332119ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:44.641841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.203018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:19:44.641932Z","caller":"traceutil/trace.go:171","msg":"trace[1199710335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"781.351796ms","start":"2026-04-28T07:19:43.860524Z","end":"2026-04-28T07:19:44.641876Z","steps":["trace[1199710335] 'agreement among raft nodes before linearized reading' (duration: 781.184628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:44.642028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:43.860501Z","time spent":"781.515934ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:19:44.642387Z","caller":"traceutil/trace.go:171","msg":"trace[2024560247] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:544; }","duration":"317.897467ms","start":"2026-04-28T07:19:44.32354Z","end":"2026-04-28T07:19:44.641438Z","steps":["trace[2024560247] 'read index received' (duration: 178.040245ms)","trace[2024560247] 'applied index is now lower than readState.Index' (duration: 139.856332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:45.297222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.832422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:45.297406Z","caller":"traceutil/trace.go:171","msg":"trace[1644682976] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"654.930075ms","start":"2026-04-28T07:19:44.642439Z","end":"2026-04-28T07:19:45.297369Z","steps":["trace[1644682976] 'read index received' (duration: 338.758736ms)","trace[1644682976] 'applied index is now lower than readState.Index' (duration: 316.169649ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:45.297499Z","caller":"traceutil/trace.go:171","msg":"trace[2129360974] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"882.11203ms","start":"2026-04-28T07:19:44.415376Z","end":"2026-04-28T07:19:45.297488Z","steps":["trace[2129360974] 'process raft request' (duration: 565.871528ms)","trace[2129360974] 'compare' (duration: 315.516516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:45.29757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.415341Z","time spent":"882.202615ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:19:45.297962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.130187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T07:19:45.298072Z","caller":"traceutil/trace.go:171","msg":"trace[235288095] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:527; }","duration":"972.476445ms","start":"2026-04-28T07:19:44.325572Z","end":"2026-04-28T07:19:45.298049Z","steps":["trace[235288095] 'agreement among raft nodes before linearized reading' (duration: 972.054954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.298125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.325554Z","time spent":"972.560079ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-28T07:19:45.36934Z","caller":"traceutil/trace.go:171","msg":"trace[267781451] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"721.909305ms","start":"2026-04-28T07:19:44.64739Z","end":"2026-04-28T07:19:45.369299Z","steps":["trace[267781451] 'process raft request' (duration: 721.515106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.369591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.647368Z","time spent":"722.101346ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:45.369578Z","caller":"traceutil/trace.go:171","msg":"trace[186817233] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"712.823344ms","start":"2026-04-28T07:19:44.656727Z","end":"2026-04-28T07:19:45.369551Z","steps":["trace[186817233] 'process raft request' (duration: 712.480746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.369836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.656698Z","time spent":"713.034065ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:45.369884Z","caller":"traceutil/trace.go:171","msg":"trace[1355300294] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"700.204694ms","start":"2026-04-28T07:19:44.669658Z","end":"2026-04-28T07:19:45.369863Z","steps":["trace[1355300294] 'process raft request' (duration: 699.796474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.370071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.669638Z","time spent":"700.32841ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:45.70409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.036032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:45.704372Z","caller":"traceutil/trace.go:171","msg":"trace[1136646966] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"650.332686ms","start":"2026-04-28T07:19:45.053993Z","end":"2026-04-28T07:19:45.704326Z","steps":["trace[1136646966] 'process raft request' (duration: 485.978208ms)","trace[1136646966] 'compare' (duration: 163.932747ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:45.704516Z","caller":"traceutil/trace.go:171","msg":"trace[953383575] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:549; }","duration":"407.064592ms","start":"2026-04-28T07:19:45.297435Z","end":"2026-04-28T07:19:45.704499Z","steps":["trace[953383575] 'read index received' (duration: 71.390081ms)","trace[953383575] 'applied index is now lower than readState.Index' (duration: 335.673491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:45.704578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.053954Z","time spent":"650.513405ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:45.704667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061103003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:45.704598Z","caller":"traceutil/trace.go:171","msg":"trace[544061125] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"380.092158ms","start":"2026-04-28T07:19:45.324498Z","end":"2026-04-28T07:19:45.70459Z","steps":["trace[544061125] 'process raft request' (duration: 379.716549ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:45.704747Z","caller":"traceutil/trace.go:171","msg":"trace[181720529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.061183797s","start":"2026-04-28T07:19:44.643553Z","end":"2026-04-28T07:19:45.704737Z","steps":["trace[181720529] 'agreement among raft nodes before linearized reading' (duration: 1.061065151s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.704963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:44.643493Z","time spent":"1.061456821s","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:19:45.704908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.324457Z","time spent":"380.378742ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:19:45.7746Z","caller":"traceutil/trace.go:171","msg":"trace[553434863] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"389.899437ms","start":"2026-04-28T07:19:45.384678Z","end":"2026-04-28T07:19:45.774577Z","steps":["trace[553434863] 'process raft request' (duration: 389.327369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.774861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.384653Z","time spent":"390.135968ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:45.775291Z","caller":"traceutil/trace.go:171","msg":"trace[1559182587] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"349.229608ms","start":"2026-04-28T07:19:45.426038Z","end":"2026-04-28T07:19:45.775267Z","steps":["trace[1559182587] 'process raft request' (duration: 348.104903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.775478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.426006Z","time spent":"349.353174ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:45.775657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.239883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T07:19:45.775702Z","caller":"traceutil/trace.go:171","msg":"trace[1616686579] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:534; }","duration":"475.323057ms","start":"2026-04-28T07:19:45.300366Z","end":"2026-04-28T07:19:45.775689Z","steps":["trace[1616686579] 'agreement among raft nodes before linearized reading' (duration: 475.243323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:45.775769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.300345Z","time spent":"475.404432ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T07:19:46.016542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.490897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:46.017056Z","caller":"traceutil/trace.go:171","msg":"trace[1873106159] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"222.433055ms","start":"2026-04-28T07:19:45.794598Z","end":"2026-04-28T07:19:46.017018Z","steps":["trace[1873106159] 'process raft request' (duration: 90.363418ms)","trace[1873106159] 'compare' (duration: 131.33159ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:46.046747Z","caller":"traceutil/trace.go:171","msg":"trace[210361082] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"182.23114ms","start":"2026-04-28T07:19:45.864491Z","end":"2026-04-28T07:19:46.046722Z","steps":["trace[210361082] 'process raft request' (duration: 182.01857ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:46.818216Z","caller":"traceutil/trace.go:171","msg":"trace[1253948551] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"819.863854ms","start":"2026-04-28T07:19:45.998307Z","end":"2026-04-28T07:19:46.818171Z","steps":["trace[1253948551] 'process raft request' (duration: 782.493278ms)","trace[1253948551] 'compare' (duration: 36.558754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:46.818848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:45.99828Z","time spent":"820.322927ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:19:46.822847Z","caller":"traceutil/trace.go:171","msg":"trace[1003947441] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"779.657027ms","start":"2026-04-28T07:19:46.04315Z","end":"2026-04-28T07:19:46.822807Z","steps":["trace[1003947441] 'process raft request' (duration: 774.933472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:46.823108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.043132Z","time spent":"779.888129ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:47.443548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.565524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:19:47.443688Z","caller":"traceutil/trace.go:171","msg":"trace[1808201797] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"1.11594394s","start":"2026-04-28T07:19:46.327695Z","end":"2026-04-28T07:19:47.443639Z","steps":["trace[1808201797] 'read index received' (duration: 452.636358ms)","trace[1808201797] 'applied index is now lower than readState.Index' (duration: 663.306242ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:47.443691Z","caller":"traceutil/trace.go:171","msg":"trace[1502879268] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.37182453s","start":"2026-04-28T07:19:46.071836Z","end":"2026-04-28T07:19:47.44366Z","steps":["trace[1502879268] 'process raft request' (duration: 1.178053115s)","trace[1502879268] 'compare' (duration: 193.27293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:47.443887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116156781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T07:19:47.443879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.071815Z","time spent":"1.371924985s","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:47.443934Z","caller":"traceutil/trace.go:171","msg":"trace[782033091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.116277616s","start":"2026-04-28T07:19:46.327647Z","end":"2026-04-28T07:19:47.443924Z","steps":["trace[782033091] 'agreement among raft nodes before linearized reading' (duration: 1.116092227s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:47.443978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.32763Z","time spent":"1.116336399s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T07:19:47.500478Z","caller":"traceutil/trace.go:171","msg":"trace[891309962] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"649.332956ms","start":"2026-04-28T07:19:46.851113Z","end":"2026-04-28T07:19:47.500446Z","steps":["trace[891309962] 'process raft request' (duration: 648.933466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:47.500676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.851087Z","time spent":"649.481674ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:47.500807Z","caller":"traceutil/trace.go:171","msg":"trace[338190193] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"585.956703ms","start":"2026-04-28T07:19:46.914824Z","end":"2026-04-28T07:19:47.500781Z","steps":["trace[338190193] 'process raft request' (duration: 585.582215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:47.500898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.9148Z","time spent":"586.057788ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:19:48.08277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.052943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-28T07:19:48.083014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.603312Z","time spent":"479.694262ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T07:19:48.083214Z","caller":"traceutil/trace.go:171","msg":"trace[788466498] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:561; }","duration":"639.47652ms","start":"2026-04-28T07:19:47.443713Z","end":"2026-04-28T07:19:48.083189Z","steps":["trace[788466498] 'read index received' (duration: 56.27091ms)","trace[788466498] 'applied index is now lower than readState.Index' (duration: 583.20395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:48.083147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.285248ms","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-04-28T07:19:48.083345Z","caller":"traceutil/trace.go:171","msg":"trace[1988098546] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:541; }","duration":"252.52388ms","start":"2026-04-28T07:19:47.830801Z","end":"2026-04-28T07:19:48.083325Z","steps":["trace[1988098546] 'range keys from in-memory index tree' (duration: 252.264117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.083447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.173889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T07:19:48.083528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436737857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T07:19:48.083587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199145229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:48.083619Z","caller":"traceutil/trace.go:171","msg":"trace[1948932375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"1.436819841s","start":"2026-04-28T07:19:46.646772Z","end":"2026-04-28T07:19:48.083592Z","steps":["trace[1948932375] 'agreement among raft nodes before linearized reading' (duration: 1.43660244s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:48.083628Z","caller":"traceutil/trace.go:171","msg":"trace[135088977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"708.352628ms","start":"2026-04-28T07:19:47.375193Z","end":"2026-04-28T07:19:48.083546Z","steps":["trace[135088977] 'agreement among raft nodes before linearized reading' (duration: 708.045832ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:48.083653Z","caller":"traceutil/trace.go:171","msg":"trace[941890615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"1.199261175s","start":"2026-04-28T07:19:46.884376Z","end":"2026-04-28T07:19:48.083637Z","steps":["trace[941890615] 'agreement among raft nodes before linearized reading' (duration: 1.198966521s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.083672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.646738Z","time spent":"1.436923956s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:19:48.083675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.375174Z","time spent":"708.489654ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:19:48.083689Z","caller":"traceutil/trace.go:171","msg":"trace[1995834775] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"607.691323ms","start":"2026-04-28T07:19:47.475985Z","end":"2026-04-28T07:19:48.083676Z","steps":["trace[1995834775] 'process raft request' (duration: 607.471402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.083693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.884355Z","time spent":"1.199328958s","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:19:48.083794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.47597Z","time spent":"607.755496ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:48.083802Z","caller":"traceutil/trace.go:171","msg":"trace[620561512] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"634.286281ms","start":"2026-04-28T07:19:47.449503Z","end":"2026-04-28T07:19:48.08379Z","steps":["trace[620561512] 'process raft request' (duration: 633.425388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.084006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.449464Z","time spent":"634.389216ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:19:48.084787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438224051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T07:19:48.084868Z","caller":"traceutil/trace.go:171","msg":"trace[634388825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"1.438349777s","start":"2026-04-28T07:19:46.646502Z","end":"2026-04-28T07:19:48.084852Z","steps":["trace[634388825] 'agreement among raft nodes before linearized reading' (duration: 1.436763408s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.084926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:46.646482Z","time spent":"1.438433722s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T07:19:48.086035Z","caller":"traceutil/trace.go:171","msg":"trace[1223109760] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"560.331098ms","start":"2026-04-28T07:19:47.525686Z","end":"2026-04-28T07:19:48.086017Z","steps":["trace[1223109760] 'process raft request' (duration: 559.916878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.086421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.525654Z","time spent":"560.709127ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:48.086225Z","caller":"traceutil/trace.go:171","msg":"trace[786409032] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"449.168337ms","start":"2026-04-28T07:19:47.637037Z","end":"2026-04-28T07:19:48.086205Z","steps":["trace[786409032] 'process raft request' (duration: 448.782368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.086572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:47.637015Z","time spent":"449.532946ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:19:48.577959Z","caller":"traceutil/trace.go:171","msg":"trace[1399033781] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"274.146784ms","start":"2026-04-28T07:19:48.303775Z","end":"2026-04-28T07:19:48.577922Z","steps":["trace[1399033781] 'read index received' (duration: 273.25027ms)","trace[1399033781] 'applied index is now lower than readState.Index' (duration: 895.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:48.578073Z","caller":"traceutil/trace.go:171","msg":"trace[5187816] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"284.637389ms","start":"2026-04-28T07:19:48.29339Z","end":"2026-04-28T07:19:48.578028Z","steps":["trace[5187816] 'process raft request' (duration: 283.927163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:48.578481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.660999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-28T07:19:48.57857Z","caller":"traceutil/trace.go:171","msg":"trace[1309788751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"274.828799ms","start":"2026-04-28T07:19:48.303724Z","end":"2026-04-28T07:19:48.578553Z","steps":["trace[1309788751] 'agreement among raft nodes before linearized reading' (duration: 274.342235ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:53.911454Z","caller":"traceutil/trace.go:171","msg":"trace[1909784133] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"160.550049ms","start":"2026-04-28T07:19:53.750881Z","end":"2026-04-28T07:19:53.911431Z","steps":["trace[1909784133] 'process raft request' (duration: 158.643553ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:54.52344Z","caller":"traceutil/trace.go:171","msg":"trace[1627061229] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"304.08166ms","start":"2026-04-28T07:19:54.219338Z","end":"2026-04-28T07:19:54.523419Z","steps":["trace[1627061229] 'read index received' (duration: 295.742582ms)","trace[1627061229] 'applied index is now lower than readState.Index' (duration: 8.338088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:54.523607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.260088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cbb8r\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-28T07:19:54.523591Z","caller":"traceutil/trace.go:171","msg":"trace[1220742796] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"317.177246ms","start":"2026-04-28T07:19:54.206391Z","end":"2026-04-28T07:19:54.523568Z","steps":["trace[1220742796] 'process raft request' (duration: 308.627518ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:19:54.523641Z","caller":"traceutil/trace.go:171","msg":"trace[1402989240] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cbb8r; range_end:; response_count:1; response_revision:583; }","duration":"304.334162ms","start":"2026-04-28T07:19:54.219294Z","end":"2026-04-28T07:19:54.523629Z","steps":["trace[1402989240] 'agreement among raft nodes before linearized reading' (duration: 304.197985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:54.52367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:54.219281Z","time spent":"304.382994ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-cbb8r\" "} {"level":"warn","ts":"2026-04-28T07:19:54.523812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:54.206375Z","time spent":"317.26788ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:55.228503Z","caller":"traceutil/trace.go:171","msg":"trace[1252337135] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"812.575142ms","start":"2026-04-28T07:19:54.415895Z","end":"2026-04-28T07:19:55.22847Z","steps":["trace[1252337135] 'process raft request' (duration: 799.348909ms)","trace[1252337135] 'compare' (duration: 12.842584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:55.22863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:54.415875Z","time spent":"812.694388ms","remote":"127.0.0.1:40296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:55.283375Z","caller":"traceutil/trace.go:171","msg":"trace[1357233824] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"396.791729ms","start":"2026-04-28T07:19:54.886566Z","end":"2026-04-28T07:19:55.283358Z","steps":["trace[1357233824] 'read index received' (duration: 328.578931ms)","trace[1357233824] 'applied index is now lower than readState.Index' (duration: 68.212148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:19:55.28349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.910374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:55.28351Z","caller":"traceutil/trace.go:171","msg":"trace[1440203538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"396.985328ms","start":"2026-04-28T07:19:54.886519Z","end":"2026-04-28T07:19:55.283504Z","steps":["trace[1440203538] 'agreement among raft nodes before linearized reading' (duration: 396.908614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:55.283528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:54.8865Z","time spent":"397.02337ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:19:55.283795Z","caller":"traceutil/trace.go:171","msg":"trace[259200473] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"739.343636ms","start":"2026-04-28T07:19:54.5444Z","end":"2026-04-28T07:19:55.283744Z","steps":["trace[259200473] 'process raft request' (duration: 738.870631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:55.2841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:54.544376Z","time spent":"739.566417ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:56.281029Z","caller":"traceutil/trace.go:171","msg":"trace[2121370681] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"394.043359ms","start":"2026-04-28T07:19:55.886955Z","end":"2026-04-28T07:19:56.280999Z","steps":["trace[2121370681] 'read index received' (duration: 393.110551ms)","trace[2121370681] 'applied index is now lower than readState.Index' (duration: 931.528µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:19:56.281182Z","caller":"traceutil/trace.go:171","msg":"trace[616730641] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"579.786826ms","start":"2026-04-28T07:19:55.701382Z","end":"2026-04-28T07:19:56.281169Z","steps":["trace[616730641] 'process raft request' (duration: 579.011647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:56.281337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:55.701359Z","time spent":"579.86006ms","remote":"127.0.0.1:40296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:19:56.281331Z","caller":"traceutil/trace.go:171","msg":"trace[1910298613] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"194.813365ms","start":"2026-04-28T07:19:56.08649Z","end":"2026-04-28T07:19:56.281303Z","steps":["trace[1910298613] 'process raft request' (duration: 194.679978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:56.281686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.733854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:19:56.281743Z","caller":"traceutil/trace.go:171","msg":"trace[2109530123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"394.791006ms","start":"2026-04-28T07:19:55.886938Z","end":"2026-04-28T07:19:56.281729Z","steps":["trace[2109530123] 'agreement among raft nodes before linearized reading' (duration: 394.690341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:19:56.281782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:19:55.886908Z","time spent":"394.865ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:19:56.282103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.50451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T07:19:56.282215Z","caller":"traceutil/trace.go:171","msg":"trace[2134451541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"114.697099ms","start":"2026-04-28T07:19:56.167493Z","end":"2026-04-28T07:19:56.28219Z","steps":["trace[2134451541] 'agreement among raft nodes before linearized reading' (duration: 114.235556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:01.552748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.736231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-28T07:20:01.552847Z","caller":"traceutil/trace.go:171","msg":"trace[1356431449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"111.865777ms","start":"2026-04-28T07:20:01.44096Z","end":"2026-04-28T07:20:01.552826Z","steps":["trace[1356431449] 'range keys from in-memory index tree' (duration: 111.52247ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:20:10.152389Z","caller":"traceutil/trace.go:171","msg":"trace[46278017] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"238.488464ms","start":"2026-04-28T07:20:09.913866Z","end":"2026-04-28T07:20:10.152355Z","steps":["trace[46278017] 'process raft request' (duration: 238.269513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:12.887324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619742042967540094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:20:13.387913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619742042967540094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:20:13.590408Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209912669s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:20:13.591064Z","caller":"traceutil/trace.go:171","msg":"trace[2065403664] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"1.204026682s","start":"2026-04-28T07:20:12.387013Z","end":"2026-04-28T07:20:13.59104Z","steps":["trace[2065403664] 'read index received' (duration: 1.203561589s)","trace[2065403664] 'applied index is now lower than readState.Index' (duration: 463.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:20:13.591181Z","caller":"traceutil/trace.go:171","msg":"trace[1074492894] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.210720929s","start":"2026-04-28T07:20:12.38038Z","end":"2026-04-28T07:20:13.591101Z","steps":["trace[1074492894] 'process raft request' (duration: 1.210491297s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.380354Z","time spent":"1.210876598s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:20:13.591391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204344139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T07:20:13.591438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.544299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:20:13.591455Z","caller":"traceutil/trace.go:171","msg":"trace[1563011307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"1.204453904s","start":"2026-04-28T07:20:12.386983Z","end":"2026-04-28T07:20:13.591437Z","steps":["trace[1563011307] 'agreement among raft nodes before linearized reading' (duration: 1.204192521s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196192748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:20:13.591498Z","caller":"traceutil/trace.go:171","msg":"trace[594601625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"703.634943ms","start":"2026-04-28T07:20:12.887847Z","end":"2026-04-28T07:20:13.591481Z","steps":["trace[594601625] 'agreement among raft nodes before linearized reading' (duration: 703.454494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.386971Z","time spent":"1.204527338s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:20:13.591557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.887835Z","time spent":"703.714097ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:20:13.591558Z","caller":"traceutil/trace.go:171","msg":"trace[823204230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"1.196282853s","start":"2026-04-28T07:20:12.395253Z","end":"2026-04-28T07:20:13.591536Z","steps":["trace[823204230] 'agreement among raft nodes before linearized reading' (duration: 1.195976427s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.395219Z","time spent":"1.196374918s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:20:13.591607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196206438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:20:13.591688Z","caller":"traceutil/trace.go:171","msg":"trace[1555804178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"1.196305803s","start":"2026-04-28T07:20:12.39536Z","end":"2026-04-28T07:20:13.591666Z","steps":["trace[1555804178] 'agreement among raft nodes before linearized reading' (duration: 1.196058041s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.39527Z","time spent":"1.196453601s","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:20:13.591883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.614231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:20:13.591927Z","caller":"traceutil/trace.go:171","msg":"trace[431554322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"978.687655ms","start":"2026-04-28T07:20:12.613227Z","end":"2026-04-28T07:20:13.591915Z","steps":["trace[431554322] 'agreement among raft nodes before linearized reading' (duration: 978.047253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:13.591954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:12.61321Z","time spent":"978.738027ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T07:20:28.069647Z","caller":"traceutil/trace.go:171","msg":"trace[1469763859] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"328.431786ms","start":"2026-04-28T07:20:27.741192Z","end":"2026-04-28T07:20:28.069624Z","steps":["trace[1469763859] 'process raft request' (duration: 328.265588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.069862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.741173Z","time spent":"328.551382ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:20:28.257104Z","caller":"traceutil/trace.go:171","msg":"trace[2129979641] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:954; }","duration":"484.592512ms","start":"2026-04-28T07:20:27.772486Z","end":"2026-04-28T07:20:28.257079Z","steps":["trace[2129979641] 'read index received' (duration: 296.963646ms)","trace[2129979641] 'applied index is now lower than readState.Index' (duration: 187.627866ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:20:28.257573Z","caller":"traceutil/trace.go:171","msg":"trace[1860748269] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"515.686182ms","start":"2026-04-28T07:20:27.741872Z","end":"2026-04-28T07:20:28.257558Z","steps":["trace[1860748269] 'process raft request' (duration: 514.959956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.257682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.74185Z","time spent":"515.762536ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:20:28.257905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.384742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-f559c\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-04-28T07:20:28.257956Z","caller":"traceutil/trace.go:171","msg":"trace[1756835086] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"515.64603ms","start":"2026-04-28T07:20:27.74227Z","end":"2026-04-28T07:20:28.257916Z","steps":["trace[1756835086] 'process raft request' (duration: 514.733635ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:20:28.258004Z","caller":"traceutil/trace.go:171","msg":"trace[959799771] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-f559c; range_end:; response_count:1; response_revision:925; }","duration":"485.53979ms","start":"2026-04-28T07:20:27.772444Z","end":"2026-04-28T07:20:28.257984Z","steps":["trace[959799771] 'agreement among raft nodes before linearized reading' (duration: 485.248385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.258064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.772428Z","time spent":"485.622784ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5698,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-f559c\" "} {"level":"warn","ts":"2026-04-28T07:20:28.258111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.032151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:20:28.258145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.742241Z","time spent":"515.806978ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:20:28.258167Z","caller":"traceutil/trace.go:171","msg":"trace[229067504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"479.117495ms","start":"2026-04-28T07:20:27.779037Z","end":"2026-04-28T07:20:28.258154Z","steps":["trace[229067504] 'agreement among raft nodes before linearized reading' (duration: 479.031151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.258212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.779017Z","time spent":"479.183829ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:20:28.257973Z","caller":"traceutil/trace.go:171","msg":"trace[810298696] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"515.648601ms","start":"2026-04-28T07:20:27.742293Z","end":"2026-04-28T07:20:28.257941Z","steps":["trace[810298696] 'process raft request' (duration: 514.749196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.2584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.74228Z","time spent":"516.04921ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:20:28.258471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.717042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:20:28.258533Z","caller":"traceutil/trace.go:171","msg":"trace[1127725751] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:925; }","duration":"393.822088ms","start":"2026-04-28T07:20:27.864697Z","end":"2026-04-28T07:20:28.258519Z","steps":["trace[1127725751] 'agreement among raft nodes before linearized reading' (duration: 393.707232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.258574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.86468Z","time spent":"393.884391ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:20:28.258803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.794465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:20:28.258862Z","caller":"traceutil/trace.go:171","msg":"trace[506303946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"371.87507ms","start":"2026-04-28T07:20:27.886972Z","end":"2026-04-28T07:20:28.258847Z","steps":["trace[506303946] 'agreement among raft nodes before linearized reading' (duration: 371.793706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:20:28.258909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:20:27.886958Z","time spent":"371.940633ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:20:50.294089Z","caller":"traceutil/trace.go:171","msg":"trace[1335243347] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"105.89212ms","start":"2026-04-28T07:20:50.188174Z","end":"2026-04-28T07:20:50.294067Z","steps":["trace[1335243347] 'process raft request' (duration: 105.760543ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:59.192964Z","caller":"traceutil/trace.go:171","msg":"trace[587877802] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"246.975773ms","start":"2026-04-28T07:21:58.945959Z","end":"2026-04-28T07:21:59.192935Z","steps":["trace[587877802] 'read index received' (duration: 203.514929ms)","trace[587877802] 'applied index is now lower than readState.Index' (duration: 43.459264ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:59.193032Z","caller":"traceutil/trace.go:171","msg":"trace[551193199] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"267.836452ms","start":"2026-04-28T07:21:58.925167Z","end":"2026-04-28T07:21:59.193003Z","steps":["trace[551193199] 'process raft request' (duration: 224.384458ms)","trace[551193199] 'compare' (duration: 43.127067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:59.193217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.230416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T07:21:59.193252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.98632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T07:21:59.193289Z","caller":"traceutil/trace.go:171","msg":"trace[1914786842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"247.332031ms","start":"2026-04-28T07:21:58.945939Z","end":"2026-04-28T07:21:59.193271Z","steps":["trace[1914786842] 'agreement among raft nodes before linearized reading' (duration: 247.10367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:59.193317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.37727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:21:59.193299Z","caller":"traceutil/trace.go:171","msg":"trace[155693123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"227.077884ms","start":"2026-04-28T07:21:58.966209Z","end":"2026-04-28T07:21:59.193287Z","steps":["trace[155693123] 'agreement among raft nodes before linearized reading' (duration: 226.913906ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:59.193377Z","caller":"traceutil/trace.go:171","msg":"trace[2138464309] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1273; }","duration":"227.458324ms","start":"2026-04-28T07:21:58.965906Z","end":"2026-04-28T07:21:59.193364Z","steps":["trace[2138464309] 'agreement among raft nodes before linearized reading' (duration: 227.37753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:59.193251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.75525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:21:59.193454Z","caller":"traceutil/trace.go:171","msg":"trace[818573393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"228.994742ms","start":"2026-04-28T07:21:58.964443Z","end":"2026-04-28T07:21:59.193437Z","steps":["trace[818573393] 'agreement among raft nodes before linearized reading' (duration: 228.675965ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:23.00391Z","caller":"traceutil/trace.go:171","msg":"trace[690179822] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"119.592984ms","start":"2026-04-28T07:22:22.884296Z","end":"2026-04-28T07:22:23.003889Z","steps":["trace[690179822] 'read index received' (duration: 119.445097ms)","trace[690179822] 'applied index is now lower than readState.Index' (duration: 144.687µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:23.003962Z","caller":"traceutil/trace.go:171","msg":"trace[419335703] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"137.958826ms","start":"2026-04-28T07:22:22.865972Z","end":"2026-04-28T07:22:23.00393Z","steps":["trace[419335703] 'process raft request' (duration: 137.778916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:23.004087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.810657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"warn","ts":"2026-04-28T07:22:23.004072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.744002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:23.004123Z","caller":"traceutil/trace.go:171","msg":"trace[586908507] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:1361; }","duration":"107.8701ms","start":"2026-04-28T07:22:22.896242Z","end":"2026-04-28T07:22:23.004112Z","steps":["trace[586908507] 'agreement among raft nodes before linearized reading' (duration: 107.787206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:23.004168Z","caller":"traceutil/trace.go:171","msg":"trace[72024232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"119.871388ms","start":"2026-04-28T07:22:22.884271Z","end":"2026-04-28T07:22:23.004142Z","steps":["trace[72024232] 'agreement among raft nodes before linearized reading' (duration: 119.70158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:23.004403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.892259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-public/\" range_end:\"/registry/daemonsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:23.004444Z","caller":"traceutil/trace.go:171","msg":"trace[726567997] range","detail":"{range_begin:/registry/daemonsets/kube-public/; range_end:/registry/daemonsets/kube-public0; response_count:0; response_revision:1361; }","duration":"103.971183ms","start":"2026-04-28T07:22:22.900463Z","end":"2026-04-28T07:22:23.004434Z","steps":["trace[726567997] 'agreement among raft nodes before linearized reading' (duration: 103.891449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:25.515968Z","caller":"traceutil/trace.go:171","msg":"trace[1147084760] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"182.907016ms","start":"2026-04-28T07:22:25.333038Z","end":"2026-04-28T07:22:25.515945Z","steps":["trace[1147084760] 'process raft request' (duration: 182.720196ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:25.516187Z","caller":"traceutil/trace.go:171","msg":"trace[2107992935] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"183.042223ms","start":"2026-04-28T07:22:25.3331Z","end":"2026-04-28T07:22:25.516143Z","steps":["trace[2107992935] 'process raft request' (duration: 182.804021ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:25.516325Z","caller":"traceutil/trace.go:171","msg":"trace[591884126] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1430; }","duration":"167.656332ms","start":"2026-04-28T07:22:25.348636Z","end":"2026-04-28T07:22:25.516293Z","steps":["trace[591884126] 'read index received' (duration: 167.074703ms)","trace[591884126] 'applied index is now lower than readState.Index' (duration: 579.309µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:25.516418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.758338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-04-28T07:22:25.516491Z","caller":"traceutil/trace.go:171","msg":"trace[833538379] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1373; }","duration":"167.871383ms","start":"2026-04-28T07:22:25.348601Z","end":"2026-04-28T07:22:25.516473Z","steps":["trace[833538379] 'agreement among raft nodes before linearized reading' (duration: 167.706305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.516616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.248528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/\" range_end:\"/registry/pods/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-28T07:22:25.516668Z","caller":"traceutil/trace.go:171","msg":"trace[1714520393] range","detail":"{range_begin:/registry/pods/local-path-storage/; range_end:/registry/pods/local-path-storage0; response_count:1; response_revision:1373; }","duration":"148.315441ms","start":"2026-04-28T07:22:25.368339Z","end":"2026-04-28T07:22:25.516654Z","steps":["trace[1714520393] 'agreement among raft nodes before linearized reading' (duration: 148.163284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.516689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.045442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:65232"} {"level":"info","ts":"2026-04-28T07:22:25.516725Z","caller":"traceutil/trace.go:171","msg":"trace[1560578758] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1373; }","duration":"168.089745ms","start":"2026-04-28T07:22:25.348626Z","end":"2026-04-28T07:22:25.516715Z","steps":["trace[1560578758] 'agreement among raft nodes before linearized reading' (duration: 167.835312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.516722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.898829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-04-28T07:22:25.516776Z","caller":"traceutil/trace.go:171","msg":"trace[1914267526] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1373; }","duration":"145.997734ms","start":"2026-04-28T07:22:25.370764Z","end":"2026-04-28T07:22:25.516762Z","steps":["trace[1914267526] 'agreement among raft nodes before linearized reading' (duration: 145.835516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.97664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.768159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:25.976716Z","caller":"traceutil/trace.go:171","msg":"trace[887082424] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1373; }","duration":"266.913617ms","start":"2026-04-28T07:22:25.709785Z","end":"2026-04-28T07:22:25.976699Z","steps":["trace[887082424] 'range keys from in-memory index tree' (duration: 266.613402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.976738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.683798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-04-28T07:22:25.976791Z","caller":"traceutil/trace.go:171","msg":"trace[1787721544] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1373; }","duration":"369.788114ms","start":"2026-04-28T07:22:25.606987Z","end":"2026-04-28T07:22:25.976775Z","steps":["trace[1787721544] 'range keys from in-memory index tree' (duration: 369.567882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.976824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:25.606962Z","time spent":"369.853557ms","remote":"127.0.0.1:40302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3039,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-04-28T07:22:25.977033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.958005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-04-28T07:22:25.977066Z","caller":"traceutil/trace.go:171","msg":"trace[1675600218] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1373; }","duration":"389.017339ms","start":"2026-04-28T07:22:25.588037Z","end":"2026-04-28T07:22:25.977054Z","steps":["trace[1675600218] 'range keys from in-memory index tree' (duration: 388.842049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:25.977094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:25.588021Z","time spent":"389.065711ms","remote":"127.0.0.1:40302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":392,"request content":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" "} {"level":"info","ts":"2026-04-28T07:22:27.209473Z","caller":"traceutil/trace.go:171","msg":"trace[149286679] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"147.2969ms","start":"2026-04-28T07:22:27.062138Z","end":"2026-04-28T07:22:27.209435Z","steps":["trace[149286679] 'read index received' (duration: 147.082549ms)","trace[149286679] 'applied index is now lower than readState.Index' (duration: 212.961µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:27.209523Z","caller":"traceutil/trace.go:171","msg":"trace[1680515564] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"171.318699ms","start":"2026-04-28T07:22:27.038164Z","end":"2026-04-28T07:22:27.209483Z","steps":["trace[1680515564] 'process raft request' (duration: 171.044185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:27.209827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.631037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:911"} {"level":"info","ts":"2026-04-28T07:22:27.209919Z","caller":"traceutil/trace.go:171","msg":"trace[133604250] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1374; }","duration":"147.773274ms","start":"2026-04-28T07:22:27.062125Z","end":"2026-04-28T07:22:27.209898Z","steps":["trace[133604250] 'agreement among raft nodes before linearized reading' (duration: 147.469849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:27.278568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.79526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-04-28T07:22:27.27861Z","caller":"traceutil/trace.go:171","msg":"trace[1995625402] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"198.680736ms","start":"2026-04-28T07:22:27.079902Z","end":"2026-04-28T07:22:27.278583Z","steps":["trace[1995625402] 'process raft request' (duration: 198.305467ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:27.278652Z","caller":"traceutil/trace.go:171","msg":"trace[199290710] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1375; }","duration":"209.909026ms","start":"2026-04-28T07:22:27.068725Z","end":"2026-04-28T07:22:27.278634Z","steps":["trace[199290710] 'agreement among raft nodes before linearized reading' (duration: 209.663233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:27.454395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.328636ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T07:22:27.454486Z","caller":"traceutil/trace.go:171","msg":"trace[932586063] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"162.244268ms","start":"2026-04-28T07:22:27.292224Z","end":"2026-04-28T07:22:27.454468Z","steps":["trace[932586063] 'read index received' (duration: 36.786685ms)","trace[932586063] 'applied index is now lower than readState.Index' (duration: 125.455773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:27.454822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.972331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:911"} {"level":"warn","ts":"2026-04-28T07:22:27.454814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.704394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:22:27.45486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.761484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:22:27.454882Z","caller":"traceutil/trace.go:171","msg":"trace[2126598473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1375; }","duration":"124.845961ms","start":"2026-04-28T07:22:27.330022Z","end":"2026-04-28T07:22:27.454868Z","steps":["trace[2126598473] 'agreement among raft nodes before linearized reading' (duration: 124.661232ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:27.454872Z","caller":"traceutil/trace.go:171","msg":"trace[1753554438] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1375; }","duration":"158.053876ms","start":"2026-04-28T07:22:27.296805Z","end":"2026-04-28T07:22:27.454859Z","steps":["trace[1753554438] 'agreement among raft nodes before linearized reading' (duration: 157.806583ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:27.454941Z","caller":"traceutil/trace.go:171","msg":"trace[952842702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1375; }","duration":"107.886581ms","start":"2026-04-28T07:22:27.347036Z","end":"2026-04-28T07:22:27.454923Z","steps":["trace[952842702] 'agreement among raft nodes before linearized reading' (duration: 107.652219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:27.456238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.505262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52694"} {"level":"info","ts":"2026-04-28T07:22:27.456432Z","caller":"traceutil/trace.go:171","msg":"trace[1671846042] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1375; }","duration":"164.172337ms","start":"2026-04-28T07:22:27.292191Z","end":"2026-04-28T07:22:27.456363Z","steps":["trace[1671846042] 'agreement among raft nodes before linearized reading' (duration: 162.384085ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:27.767955Z","caller":"traceutil/trace.go:171","msg":"trace[1071356111] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"146.766662ms","start":"2026-04-28T07:22:27.621149Z","end":"2026-04-28T07:22:27.767916Z","steps":["trace[1071356111] 'read index received' (duration: 133.393894ms)","trace[1071356111] 'applied index is now lower than readState.Index' (duration: 13.371868ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:27.767988Z","caller":"traceutil/trace.go:171","msg":"trace[1974400978] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"212.604552ms","start":"2026-04-28T07:22:27.555338Z","end":"2026-04-28T07:22:27.767943Z","steps":["trace[1974400978] 'process raft request' (duration: 199.188861ms)","trace[1974400978] 'compare' (duration: 13.163968ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:27.768122Z","caller":"traceutil/trace.go:171","msg":"trace[1978459705] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"212.036413ms","start":"2026-04-28T07:22:27.556054Z","end":"2026-04-28T07:22:27.768091Z","steps":["trace[1978459705] 'process raft request' (duration: 211.812452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:27.768156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.969273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:22:27.768212Z","caller":"traceutil/trace.go:171","msg":"trace[1069420257] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1379; }","duration":"147.09724ms","start":"2026-04-28T07:22:27.621101Z","end":"2026-04-28T07:22:27.768198Z","steps":["trace[1069420257] 'agreement among raft nodes before linearized reading' (duration: 146.979264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:29.410084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.40242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:22:29.410173Z","caller":"traceutil/trace.go:171","msg":"trace[1902330047] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"124.407628ms","start":"2026-04-28T07:22:29.28575Z","end":"2026-04-28T07:22:29.410158Z","steps":["trace[1902330047] 'read index received' (duration: 11.809528ms)","trace[1902330047] 'applied index is now lower than readState.Index' (duration: 112.59693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:29.410306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.560306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-04-28T07:22:29.41034Z","caller":"traceutil/trace.go:171","msg":"trace[1503140583] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"126.639462ms","start":"2026-04-28T07:22:29.283671Z","end":"2026-04-28T07:22:29.410311Z","steps":["trace[1503140583] 'process raft request' (duration: 13.932606ms)","trace[1503140583] 'compare' (duration: 112.317076ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:29.410372Z","caller":"traceutil/trace.go:171","msg":"trace[989992266] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"124.634929ms","start":"2026-04-28T07:22:29.285707Z","end":"2026-04-28T07:22:29.410342Z","steps":["trace[989992266] 'agreement among raft nodes before linearized reading' (duration: 124.535964ms)"],"step_count":1}