{"level":"warn","ts":"2026-04-28T06:42:48.984171Z","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-28T06:42:48.984266Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-28T06:42:48.984458Z","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-28T06:42:48.984486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-28T06:42:48.984536Z","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-28T06:42:48.985307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-28T06:42:48.985457Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-28T06:42:48.989448Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.709552ms"} {"level":"info","ts":"2026-04-28T06:42:49.006191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-28T06:42:49.006338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:42:49.006413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:42:49.006437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:42:49.00645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:42:49.006511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-28T06:42:49.016752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:42:49.019138Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:42:49.020293Z","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-28T06:42:49.024426Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:42:49.025657Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:42:49.025444Z","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-28T06:42:49.025783Z","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-28T06:42:49.025801Z","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-28T06:42:49.026007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-28T06:42:49.026221Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-28T06:42:49.032735Z","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-28T06:42:49.032877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-28T06:42:49.03292Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-28T06:42:49.033104Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:42:49.033169Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:42:49.407476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:42:49.407533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:42:49.407559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-28T06:42:49.407576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:42:49.407583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-28T06:42:49.407594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:42:49.407605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-28T06:42:49.416856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:49.435486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:42:49.435497Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:42:49.435667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:42:49.43585Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:42:49.435897Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:42:49.438835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:42:49.44713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-28T06:42:49.47777Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:49.477908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:49.477971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:52.480532Z","caller":"traceutil/trace.go:171","msg":"trace[438650516] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"114.74699ms","start":"2026-04-28T06:42:52.365737Z","end":"2026-04-28T06:42:52.480484Z","steps":["trace[438650516] 'read index received' (duration: 110.010082ms)","trace[438650516] 'applied index is now lower than readState.Index' (duration: 4.735908ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:52.48063Z","caller":"traceutil/trace.go:171","msg":"trace[1617872640] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"172.761728ms","start":"2026-04-28T06:42:52.307836Z","end":"2026-04-28T06:42:52.480598Z","steps":["trace[1617872640] 'process raft request' (duration: 167.945627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:52.480957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.100912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:42:52.481046Z","caller":"traceutil/trace.go:171","msg":"trace[694552592] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:230; }","duration":"115.339971ms","start":"2026-04-28T06:42:52.365691Z","end":"2026-04-28T06:42:52.481031Z","steps":["trace[694552592] 'agreement among raft nodes before linearized reading' (duration: 114.922996ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.784484Z","caller":"traceutil/trace.go:171","msg":"trace[2040850235] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"145.915129ms","start":"2026-04-28T06:42:53.638548Z","end":"2026-04-28T06:42:53.784463Z","steps":["trace[2040850235] 'process raft request' (duration: 145.795105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:54.54191Z","caller":"traceutil/trace.go:171","msg":"trace[1297954321] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:263; }","duration":"154.350297ms","start":"2026-04-28T06:42:54.387532Z","end":"2026-04-28T06:42:54.541883Z","steps":["trace[1297954321] 'read index received' (duration: 79.336439ms)","trace[1297954321] 'applied index is now lower than readState.Index' (duration: 75.012918ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:54.541942Z","caller":"traceutil/trace.go:171","msg":"trace[1987720154] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"154.514503ms","start":"2026-04-28T06:42:54.387397Z","end":"2026-04-28T06:42:54.541911Z","steps":["trace[1987720154] 'process raft request' (duration: 154.295125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:54.54211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.556315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:42:54.542163Z","caller":"traceutil/trace.go:171","msg":"trace[1930724821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:259; }","duration":"154.657528ms","start":"2026-04-28T06:42:54.387492Z","end":"2026-04-28T06:42:54.542149Z","steps":["trace[1930724821] 'agreement among raft nodes before linearized reading' (duration: 154.535724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:54.612405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.767783ms","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-28T06:42:54.612452Z","caller":"traceutil/trace.go:171","msg":"trace[380077760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:260; }","duration":"224.97689ms","start":"2026-04-28T06:42:54.387464Z","end":"2026-04-28T06:42:54.612441Z","steps":["trace[380077760] 'agreement among raft nodes before linearized reading' (duration: 224.876476ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:54.612481Z","caller":"traceutil/trace.go:171","msg":"trace[1721438351] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"138.626052ms","start":"2026-04-28T06:42:54.473825Z","end":"2026-04-28T06:42:54.612451Z","steps":["trace[1721438351] 'process raft request' (duration: 121.393044ms)","trace[1721438351] 'compare' (duration: 16.979319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:54.612539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.414153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:42:54.612608Z","caller":"traceutil/trace.go:171","msg":"trace[1401566234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:260; }","duration":"134.510197ms","start":"2026-04-28T06:42:54.478076Z","end":"2026-04-28T06:42:54.612586Z","steps":["trace[1401566234] 'agreement among raft nodes before linearized reading' (duration: 134.385243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.05074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.725389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:00.050853Z","caller":"traceutil/trace.go:171","msg":"trace[935616610] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"315.439342ms","start":"2026-04-28T06:42:59.735396Z","end":"2026-04-28T06:43:00.050835Z","steps":["trace[935616610] 'process raft request' (duration: 131.565013ms)","trace[935616610] 'compare' (duration: 182.634816ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:00.050938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.735387Z","time spent":"315.520515ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:00.370492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.486696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:00.370726Z","caller":"traceutil/trace.go:171","msg":"trace[1236233255] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"315.664331ms","start":"2026-04-28T06:43:00.055045Z","end":"2026-04-28T06:43:00.370709Z","steps":["trace[1236233255] 'process raft request' (duration: 315.63102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.370785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.055019Z","time spent":"315.741093ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:00.370913Z","caller":"traceutil/trace.go:171","msg":"trace[862475259] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"635.453786ms","start":"2026-04-28T06:42:59.73544Z","end":"2026-04-28T06:43:00.370894Z","steps":["trace[862475259] 'process raft request' (duration: 483.499053ms)","trace[862475259] 'compare' (duration: 151.32756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:00.370991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.735425Z","time spent":"635.533499ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:00.370989Z","caller":"traceutil/trace.go:171","msg":"trace[1376335494] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:382; }","duration":"605.725888ms","start":"2026-04-28T06:42:59.765228Z","end":"2026-04-28T06:43:00.370954Z","steps":["trace[1376335494] 'read index received' (duration: 101.745722ms)","trace[1376335494] 'applied index is now lower than readState.Index' (duration: 503.977076ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:00.37103Z","caller":"traceutil/trace.go:171","msg":"trace[1845027278] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"634.654049ms","start":"2026-04-28T06:42:59.736346Z","end":"2026-04-28T06:43:00.371001Z","steps":["trace[1845027278] 'process raft request' (duration: 634.255864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.371206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.105329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"warn","ts":"2026-04-28T06:43:00.371208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.860502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-04-28T06:43:00.371225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.680792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T06:43:00.371245Z","caller":"traceutil/trace.go:171","msg":"trace[1125183504] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:373; }","duration":"169.206582ms","start":"2026-04-28T06:43:00.202029Z","end":"2026-04-28T06:43:00.371235Z","steps":["trace[1125183504] 'agreement among raft nodes before linearized reading' (duration: 169.13904ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:00.371257Z","caller":"traceutil/trace.go:171","msg":"trace[2120378835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:373; }","duration":"142.927844ms","start":"2026-04-28T06:43:00.228315Z","end":"2026-04-28T06:43:00.371243Z","steps":["trace[2120378835] 'agreement among raft nodes before linearized reading' (duration: 142.831931ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:00.371279Z","caller":"traceutil/trace.go:171","msg":"trace[809137726] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"555.751054ms","start":"2026-04-28T06:42:59.815514Z","end":"2026-04-28T06:43:00.371265Z","steps":["trace[809137726] 'agreement among raft nodes before linearized reading' (duration: 555.62749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.371337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.815502Z","time spent":"555.824346ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-28T06:43:00.371345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.593097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-28T06:43:00.371536Z","caller":"traceutil/trace.go:171","msg":"trace[886516388] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:373; }","duration":"413.817505ms","start":"2026-04-28T06:42:59.957693Z","end":"2026-04-28T06:43:00.371511Z","steps":["trace[886516388] 'agreement among raft nodes before linearized reading' (duration: 413.582086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.371611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.957669Z","time spent":"413.921048ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-28T06:43:00.371648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.736333Z","time spent":"634.758382ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:00.371919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.684511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-28T06:43:00.371993Z","caller":"traceutil/trace.go:171","msg":"trace[949277915] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:373; }","duration":"606.795535ms","start":"2026-04-28T06:42:59.765179Z","end":"2026-04-28T06:43:00.371975Z","steps":["trace[949277915] 'agreement among raft nodes before linearized reading' (duration: 605.936545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.372046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.76516Z","time spent":"606.873658ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-04-28T06:43:00.574945Z","caller":"traceutil/trace.go:171","msg":"trace[336456440] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"196.825896ms","start":"2026-04-28T06:43:00.37809Z","end":"2026-04-28T06:43:00.574916Z","steps":["trace[336456440] 'process raft request' (duration: 196.636369ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:00.93409Z","caller":"traceutil/trace.go:171","msg":"trace[416224222] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"555.252747ms","start":"2026-04-28T06:43:00.378818Z","end":"2026-04-28T06:43:00.934071Z","steps":["trace[416224222] 'process raft request' (duration: 463.82443ms)","trace[416224222] 'compare' (duration: 91.250091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:00.9343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.196844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T06:43:00.934329Z","caller":"traceutil/trace.go:171","msg":"trace[34305891] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:376; }","duration":"555.240166ms","start":"2026-04-28T06:43:00.379083Z","end":"2026-04-28T06:43:00.934323Z","steps":["trace[34305891] 'agreement among raft nodes before linearized reading' (duration: 555.122882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.934352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.37907Z","time spent":"555.276698ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-28T06:43:00.934445Z","caller":"traceutil/trace.go:171","msg":"trace[1285746887] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"553.548687ms","start":"2026-04-28T06:43:00.380886Z","end":"2026-04-28T06:43:00.934435Z","steps":["trace[1285746887] 'process raft request' (duration: 553.147683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:00.934185Z","caller":"traceutil/trace.go:171","msg":"trace[303325180] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"555.085781ms","start":"2026-04-28T06:43:00.379087Z","end":"2026-04-28T06:43:00.934173Z","steps":["trace[303325180] 'read index received' (duration: 195.605303ms)","trace[303325180] 'applied index is now lower than readState.Index' (duration: 359.478368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:00.934531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.380877Z","time spent":"553.627609ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:43:00.934662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.922215ms","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-28T06:43:00.934698Z","caller":"traceutil/trace.go:171","msg":"trace[720735277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"265.998738ms","start":"2026-04-28T06:43:00.668688Z","end":"2026-04-28T06:43:00.934687Z","steps":["trace[720735277] 'agreement among raft nodes before linearized reading' (duration: 265.938075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.934715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.347395ms","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-28T06:43:00.93479Z","caller":"traceutil/trace.go:171","msg":"trace[2016958392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"301.465369ms","start":"2026-04-28T06:43:00.633305Z","end":"2026-04-28T06:43:00.93477Z","steps":["trace[2016958392] 'agreement among raft nodes before linearized reading' (duration: 301.318414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:00.934837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.633285Z","time spent":"301.543233ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:43:00.935289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.378799Z","time spent":"555.473715ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:01.293963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.940602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:01.294323Z","caller":"traceutil/trace.go:171","msg":"trace[357733762] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"350.333614ms","start":"2026-04-28T06:43:00.943972Z","end":"2026-04-28T06:43:01.294306Z","steps":["trace[357733762] 'process raft request' (duration: 144.983897ms)","trace[357733762] 'compare' (duration: 204.840639ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:01.294351Z","caller":"traceutil/trace.go:171","msg":"trace[1561315019] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"349.063579ms","start":"2026-04-28T06:43:00.945276Z","end":"2026-04-28T06:43:01.294339Z","steps":["trace[1561315019] 'process raft request' (duration: 348.955985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.294388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.943956Z","time spent":"350.402697ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:01.294408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.945266Z","time spent":"349.11364ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:01.294338Z","caller":"traceutil/trace.go:171","msg":"trace[697629415] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:390; }","duration":"150.399108ms","start":"2026-04-28T06:43:01.143928Z","end":"2026-04-28T06:43:01.294327Z","steps":["trace[697629415] 'read index received' (duration: 29.101µs)","trace[697629415] 'applied index is now lower than readState.Index' (duration: 150.369247ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:01.294661Z","caller":"traceutil/trace.go:171","msg":"trace[130001084] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"348.974806ms","start":"2026-04-28T06:43:00.945676Z","end":"2026-04-28T06:43:01.29465Z","steps":["trace[130001084] 'process raft request' (duration: 348.611303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.294805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.945668Z","time spent":"349.09401ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:01.294951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.019109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:01.295022Z","caller":"traceutil/trace.go:171","msg":"trace[1678603064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"151.114142ms","start":"2026-04-28T06:43:01.143895Z","end":"2026-04-28T06:43:01.29501Z","steps":["trace[1678603064] 'agreement among raft nodes before linearized reading' (duration: 151.020169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.679011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.548165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:01.679122Z","caller":"traceutil/trace.go:171","msg":"trace[1986247301] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"379.754283ms","start":"2026-04-28T06:43:01.299354Z","end":"2026-04-28T06:43:01.679108Z","steps":["trace[1986247301] 'process raft request' (duration: 191.054343ms)","trace[1986247301] 'compare' (duration: 188.43636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:01.679165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.299333Z","time spent":"379.813415ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:01.679514Z","caller":"traceutil/trace.go:171","msg":"trace[1379201535] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"379.983961ms","start":"2026-04-28T06:43:01.299519Z","end":"2026-04-28T06:43:01.679503Z","steps":["trace[1379201535] 'process raft request' (duration: 379.838916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:01.679495Z","caller":"traceutil/trace.go:171","msg":"trace[1456308875] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"379.960089ms","start":"2026-04-28T06:43:01.299508Z","end":"2026-04-28T06:43:01.679468Z","steps":["trace[1456308875] 'process raft request' (duration: 379.573046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.679557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.299511Z","time spent":"380.027212ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:01.679552Z","caller":"traceutil/trace.go:171","msg":"trace[281072946] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:394; }","duration":"351.249567ms","start":"2026-04-28T06:43:01.328279Z","end":"2026-04-28T06:43:01.679529Z","steps":["trace[281072946] 'read index received' (duration: 162.07521ms)","trace[281072946] 'applied index is now lower than readState.Index' (duration: 189.173097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:01.679607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.299498Z","time spent":"380.054533ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:01.679607Z","caller":"traceutil/trace.go:171","msg":"trace[662299975] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"379.559066ms","start":"2026-04-28T06:43:01.300025Z","end":"2026-04-28T06:43:01.679584Z","steps":["trace[662299975] 'process raft request' (duration: 379.39869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.679688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.300014Z","time spent":"379.637479ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:43:01.679687Z","caller":"traceutil/trace.go:171","msg":"trace[136646724] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"375.980739ms","start":"2026-04-28T06:43:01.303681Z","end":"2026-04-28T06:43:01.679662Z","steps":["trace[136646724] 'process raft request' (duration: 375.801473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.679715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.215939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-rz8rz\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-28T06:43:01.679759Z","caller":"traceutil/trace.go:171","msg":"trace[1104761205] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-rz8rz; range_end:; response_count:1; response_revision:386; }","duration":"380.301222ms","start":"2026-04-28T06:43:01.299447Z","end":"2026-04-28T06:43:01.679748Z","steps":["trace[1104761205] 'agreement among raft nodes before linearized reading' (duration: 380.170478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.679794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.299439Z","time spent":"380.347264ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3462,"request content":"key:\"/registry/pods/kube-system/kube-proxy-rz8rz\" "} {"level":"warn","ts":"2026-04-28T06:43:01.679859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.30367Z","time spent":"376.068052ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:01.752117Z","caller":"traceutil/trace.go:171","msg":"trace[544767036] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"408.31161ms","start":"2026-04-28T06:43:01.343785Z","end":"2026-04-28T06:43:01.752097Z","steps":["trace[544767036] 'process raft request' (duration: 408.262428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.75229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.343775Z","time spent":"408.411814ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4141,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:01.752698Z","caller":"traceutil/trace.go:171","msg":"trace[1791247319] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"419.989633ms","start":"2026-04-28T06:43:01.332696Z","end":"2026-04-28T06:43:01.752685Z","steps":["trace[1791247319] 'process raft request' (duration: 419.258377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.752769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.332683Z","time spent":"420.052754ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:01.824226Z","caller":"traceutil/trace.go:171","msg":"trace[273910630] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"137.893426ms","start":"2026-04-28T06:43:01.686313Z","end":"2026-04-28T06:43:01.824206Z","steps":["trace[273910630] 'process raft request' (duration: 102.750376ms)","trace[273910630] 'compare' (duration: 35.006015ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:01.824501Z","caller":"traceutil/trace.go:171","msg":"trace[1137266302] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"134.447385ms","start":"2026-04-28T06:43:01.690045Z","end":"2026-04-28T06:43:01.824492Z","steps":["trace[1137266302] 'process raft request' (duration: 134.412204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:01.824686Z","caller":"traceutil/trace.go:171","msg":"trace[802607045] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"137.727901ms","start":"2026-04-28T06:43:01.68695Z","end":"2026-04-28T06:43:01.824677Z","steps":["trace[802607045] 'process raft request' (duration: 137.469231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:01.824763Z","caller":"traceutil/trace.go:171","msg":"trace[126366288] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"138.403945ms","start":"2026-04-28T06:43:01.686353Z","end":"2026-04-28T06:43:01.824757Z","steps":["trace[126366288] 'process raft request' (duration: 137.818084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:01.824886Z","caller":"traceutil/trace.go:171","msg":"trace[1831192366] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"138.005581ms","start":"2026-04-28T06:43:01.686855Z","end":"2026-04-28T06:43:01.82486Z","steps":["trace[1831192366] 'process raft request' (duration: 137.518233ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.072865Z","caller":"traceutil/trace.go:171","msg":"trace[1769477591] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"163.526351ms","start":"2026-04-28T06:43:01.909318Z","end":"2026-04-28T06:43:02.072844Z","steps":["trace[1769477591] 'read index received' (duration: 154.256234ms)","trace[1769477591] 'applied index is now lower than readState.Index' (duration: 9.269117ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:02.072917Z","caller":"traceutil/trace.go:171","msg":"trace[1263620229] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"164.683763ms","start":"2026-04-28T06:43:01.908206Z","end":"2026-04-28T06:43:02.07289Z","steps":["trace[1263620229] 'process raft request' (duration: 155.440667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:02.073016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.674196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4156"} {"level":"info","ts":"2026-04-28T06:43:02.073055Z","caller":"traceutil/trace.go:171","msg":"trace[2043079005] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:404; }","duration":"163.756369ms","start":"2026-04-28T06:43:01.90929Z","end":"2026-04-28T06:43:02.073046Z","steps":["trace[2043079005] 'agreement among raft nodes before linearized reading' (duration: 163.659956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:02.073129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.049724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-28T06:43:02.073176Z","caller":"traceutil/trace.go:171","msg":"trace[936442150] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:404; }","duration":"163.119316ms","start":"2026-04-28T06:43:01.910046Z","end":"2026-04-28T06:43:02.073165Z","steps":["trace[936442150] 'agreement among raft nodes before linearized reading' (duration: 163.024733ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.223028Z","caller":"traceutil/trace.go:171","msg":"trace[2122408661] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"142.165768ms","start":"2026-04-28T06:43:02.080833Z","end":"2026-04-28T06:43:02.222998Z","steps":["trace[2122408661] 'process raft request' (duration: 93.731968ms)","trace[2122408661] 'compare' (duration: 48.230433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:02.465612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.366689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:02.465976Z","caller":"traceutil/trace.go:171","msg":"trace[1298170075] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"235.249272ms","start":"2026-04-28T06:43:02.230695Z","end":"2026-04-28T06:43:02.465944Z","steps":["trace[1298170075] 'process raft request' (duration: 234.996003ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.466125Z","caller":"traceutil/trace.go:171","msg":"trace[404758228] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"199.904385ms","start":"2026-04-28T06:43:02.266182Z","end":"2026-04-28T06:43:02.466086Z","steps":["trace[404758228] 'process raft request' (duration: 199.566213ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.466138Z","caller":"traceutil/trace.go:171","msg":"trace[101516153] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"237.805971ms","start":"2026-04-28T06:43:02.228314Z","end":"2026-04-28T06:43:02.46612Z","steps":["trace[101516153] 'process raft request' (duration: 110.866002ms)","trace[101516153] 'compare' (duration: 126.163732ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:02.606484Z","caller":"traceutil/trace.go:171","msg":"trace[675514970] transaction","detail":"{read_only:false; number_of_response:1; response_revision:412; }","duration":"129.607485ms","start":"2026-04-28T06:43:02.476854Z","end":"2026-04-28T06:43:02.606462Z","steps":["trace[675514970] 'process raft request' (duration: 129.542053ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.606517Z","caller":"traceutil/trace.go:171","msg":"trace[726695906] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"132.29074ms","start":"2026-04-28T06:43:02.474192Z","end":"2026-04-28T06:43:02.606483Z","steps":["trace[726695906] 'process raft request' (duration: 132.133824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.606567Z","caller":"traceutil/trace.go:171","msg":"trace[267818877] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"136.037081ms","start":"2026-04-28T06:43:02.470476Z","end":"2026-04-28T06:43:02.606513Z","steps":["trace[267818877] 'process raft request' (duration: 121.205737ms)","trace[267818877] 'compare' (duration: 14.487771ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:03.21649Z","caller":"traceutil/trace.go:171","msg":"trace[1679832209] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"163.172098ms","start":"2026-04-28T06:43:03.053279Z","end":"2026-04-28T06:43:03.216451Z","steps":["trace[1679832209] 'process raft request' (duration: 90.225524ms)","trace[1679832209] 'compare' (duration: 72.82689ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:03.43149Z","caller":"traceutil/trace.go:171","msg":"trace[814603549] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"132.829468ms","start":"2026-04-28T06:43:03.298635Z","end":"2026-04-28T06:43:03.431464Z","steps":["trace[814603549] 'read index received' (duration: 50.898747ms)","trace[814603549] 'applied index is now lower than readState.Index' (duration: 81.929611ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:03.431625Z","caller":"traceutil/trace.go:171","msg":"trace[728920069] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"210.067984ms","start":"2026-04-28T06:43:03.221533Z","end":"2026-04-28T06:43:03.431601Z","steps":["trace[728920069] 'process raft request' (duration: 128.07243ms)","trace[728920069] 'compare' (duration: 81.753405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:03.431647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.989973ms","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-28T06:43:03.431686Z","caller":"traceutil/trace.go:171","msg":"trace[546150680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"133.089016ms","start":"2026-04-28T06:43:03.298586Z","end":"2026-04-28T06:43:03.431675Z","steps":["trace[546150680] 'agreement among raft nodes before linearized reading' (duration: 132.970212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:05.820537Z","caller":"traceutil/trace.go:171","msg":"trace[1091031890] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"349.803542ms","start":"2026-04-28T06:43:05.470712Z","end":"2026-04-28T06:43:05.820516Z","steps":["trace[1091031890] 'process raft request' (duration: 349.650127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:05.82082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:05.470698Z","time spent":"349.906368ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:05.994136Z","caller":"traceutil/trace.go:171","msg":"trace[125996471] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"169.74764ms","start":"2026-04-28T06:43:05.824363Z","end":"2026-04-28T06:43:05.994111Z","steps":["trace[125996471] 'process raft request' (duration: 78.726971ms)","trace[125996471] 'compare' (duration: 90.483657ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:06.040151Z","caller":"traceutil/trace.go:171","msg":"trace[2007727187] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"215.55558ms","start":"2026-04-28T06:43:05.824565Z","end":"2026-04-28T06:43:06.040121Z","steps":["trace[2007727187] 'process raft request' (duration: 215.306358ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:06.040651Z","caller":"traceutil/trace.go:171","msg":"trace[112582540] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"210.012821ms","start":"2026-04-28T06:43:05.830605Z","end":"2026-04-28T06:43:06.040618Z","steps":["trace[112582540] 'process raft request' (duration: 209.442679ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:06.040742Z","caller":"traceutil/trace.go:171","msg":"trace[1703766561] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"213.169029ms","start":"2026-04-28T06:43:05.82752Z","end":"2026-04-28T06:43:06.040689Z","steps":["trace[1703766561] 'read index received' (duration: 75.583195ms)","trace[1703766561] 'applied index is now lower than readState.Index' (duration: 137.583224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:06.040864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.333073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:06.040903Z","caller":"traceutil/trace.go:171","msg":"trace[1949094631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"213.40911ms","start":"2026-04-28T06:43:05.827477Z","end":"2026-04-28T06:43:06.040887Z","steps":["trace[1949094631] 'agreement among raft nodes before linearized reading' (duration: 213.333913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:06.192644Z","caller":"traceutil/trace.go:171","msg":"trace[1195879994] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"108.115069ms","start":"2026-04-28T06:43:06.084509Z","end":"2026-04-28T06:43:06.192624Z","steps":["trace[1195879994] 'process raft request' (duration: 107.13288ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.187743Z","caller":"traceutil/trace.go:171","msg":"trace[1153273268] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"108.881003ms","start":"2026-04-28T06:43:16.078833Z","end":"2026-04-28T06:43:16.187714Z","steps":["trace[1153273268] 'read index received' (duration: 107.995202ms)","trace[1153273268] 'applied index is now lower than readState.Index' (duration: 884.451µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:16.187903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.056508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:16.187938Z","caller":"traceutil/trace.go:171","msg":"trace[1666733906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"109.148865ms","start":"2026-04-28T06:43:16.078779Z","end":"2026-04-28T06:43:16.187928Z","steps":["trace[1666733906] 'agreement among raft nodes before linearized reading' (duration: 109.037039ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.188046Z","caller":"traceutil/trace.go:171","msg":"trace[1324223518] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"113.168742ms","start":"2026-04-28T06:43:16.074844Z","end":"2026-04-28T06:43:16.188012Z","steps":["trace[1324223518] 'process raft request' (duration: 112.084348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.409466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.00091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:17.40955Z","caller":"traceutil/trace.go:171","msg":"trace[1084885140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"566.306ms","start":"2026-04-28T06:43:16.843225Z","end":"2026-04-28T06:43:17.409531Z","steps":["trace[1084885140] 'range keys from in-memory index tree' (duration: 565.888104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.409523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.095923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:43:17.40959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:16.843203Z","time spent":"566.379137ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:43:17.409611Z","caller":"traceutil/trace.go:171","msg":"trace[349203232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"364.224009ms","start":"2026-04-28T06:43:17.045366Z","end":"2026-04-28T06:43:17.40959Z","steps":["trace[349203232] 'range keys from in-memory index tree' (duration: 363.995797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.409586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057419993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:43:17.409652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:17.045343Z","time spent":"364.301277ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:17.40964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.075433ms","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-28T06:43:17.409683Z","caller":"traceutil/trace.go:171","msg":"trace[1884368658] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:497; }","duration":"1.057574098s","start":"2026-04-28T06:43:16.352084Z","end":"2026-04-28T06:43:17.409658Z","steps":["trace[1884368658] 'range keys from in-memory index tree' (duration: 1.057337916s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:17.40971Z","caller":"traceutil/trace.go:171","msg":"trace[1855693998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"321.19016ms","start":"2026-04-28T06:43:17.088501Z","end":"2026-04-28T06:43:17.409691Z","steps":["trace[1855693998] 'range keys from in-memory index tree' (duration: 320.598188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.409742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:16.352064Z","time spent":"1.057667714s","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-28T06:43:17.409751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:17.088481Z","time spent":"321.262147ms","remote":"127.0.0.1:34172","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-28T06:43:18.366429Z","caller":"traceutil/trace.go:171","msg":"trace[926821730] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"262.502522ms","start":"2026-04-28T06:43:18.1039Z","end":"2026-04-28T06:43:18.366402Z","steps":["trace[926821730] 'process raft request' (duration: 259.882163ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:18.371479Z","caller":"traceutil/trace.go:171","msg":"trace[1048055315] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:566; }","duration":"179.681735ms","start":"2026-04-28T06:43:18.191776Z","end":"2026-04-28T06:43:18.371458Z","steps":["trace[1048055315] 'read index received' (duration: 171.984971ms)","trace[1048055315] 'applied index is now lower than readState.Index' (duration: 7.696124ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:18.371572Z","caller":"traceutil/trace.go:171","msg":"trace[1660401739] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"255.757538ms","start":"2026-04-28T06:43:18.115784Z","end":"2026-04-28T06:43:18.371542Z","steps":["trace[1660401739] 'process raft request' (duration: 255.418189ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:18.371597Z","caller":"traceutil/trace.go:171","msg":"trace[608348285] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"246.953039ms","start":"2026-04-28T06:43:18.124635Z","end":"2026-04-28T06:43:18.371588Z","steps":["trace[608348285] 'process raft request' (duration: 246.774794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:18.371834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.052623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T06:43:18.371863Z","caller":"traceutil/trace.go:171","msg":"trace[1163557505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"180.133423ms","start":"2026-04-28T06:43:18.19172Z","end":"2026-04-28T06:43:18.371854Z","steps":["trace[1163557505] 'agreement among raft nodes before linearized reading' (duration: 180.032031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:18.609464Z","caller":"traceutil/trace.go:171","msg":"trace[1983505613] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"115.171978ms","start":"2026-04-28T06:43:18.494275Z","end":"2026-04-28T06:43:18.609447Z","steps":["trace[1983505613] 'process raft request' (duration: 114.856771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.545278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608866925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:20.045813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608866925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:20.226619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.460077321s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:43:20.22846Z","caller":"traceutil/trace.go:171","msg":"trace[35485790] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.462010826s","start":"2026-04-28T06:43:18.766372Z","end":"2026-04-28T06:43:20.228383Z","steps":["trace[35485790] 'process raft request' (duration: 1.461532955s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:20.229325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:18.766352Z","time spent":"1.462436115s","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:20.237634Z","caller":"traceutil/trace.go:171","msg":"trace[1464583361] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.466995668s","start":"2026-04-28T06:43:18.770606Z","end":"2026-04-28T06:43:20.237602Z","steps":["trace[1464583361] 'process raft request' (duration: 1.466665491s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:20.237789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:18.770582Z","time spent":"1.467114142s","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:20.238093Z","caller":"traceutil/trace.go:171","msg":"trace[74986948] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"1.193342162s","start":"2026-04-28T06:43:19.044732Z","end":"2026-04-28T06:43:20.238074Z","steps":["trace[74986948] 'read index received' (duration: 1.182811993s)","trace[74986948] 'applied index is now lower than readState.Index' (duration: 10.529439ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:20.238189Z","caller":"traceutil/trace.go:171","msg":"trace[1380093992] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.306141436s","start":"2026-04-28T06:43:18.932038Z","end":"2026-04-28T06:43:20.23818Z","steps":["trace[1380093992] 'process raft request' (duration: 1.305812339s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:20.238254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:18.932013Z","time spent":"1.306194017s","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:43:20.238438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1937085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:20.238457Z","caller":"traceutil/trace.go:171","msg":"trace[1302561750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"1.193762111s","start":"2026-04-28T06:43:19.044689Z","end":"2026-04-28T06:43:20.238451Z","steps":["trace[1302561750] 'agreement among raft nodes before linearized reading' (duration: 1.19371084s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:20.238517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.044672Z","time spent":"1.193797032s","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:20.238674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.345461ms","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-28T06:43:20.238703Z","caller":"traceutil/trace.go:171","msg":"trace[1776575441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"788.410373ms","start":"2026-04-28T06:43:19.450284Z","end":"2026-04-28T06:43:20.238694Z","steps":["trace[1776575441] 'agreement among raft nodes before linearized reading' (duration: 788.313031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:20.238723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.450253Z","time spent":"788.465813ms","remote":"127.0.0.1:34172","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-28T06:43:20.238747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.960892ms","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-28T06:43:20.238766Z","caller":"traceutil/trace.go:171","msg":"trace[1655593654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"228.057714ms","start":"2026-04-28T06:43:20.010702Z","end":"2026-04-28T06:43:20.23876Z","steps":["trace[1655593654] 'agreement among raft nodes before linearized reading' (duration: 227.980633ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:22.170039Z","caller":"traceutil/trace.go:171","msg":"trace[1912147200] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"124.855996ms","start":"2026-04-28T06:43:22.045122Z","end":"2026-04-28T06:43:22.169978Z","steps":["trace[1912147200] 'read index received' (duration: 124.739284ms)","trace[1912147200] 'applied index is now lower than readState.Index' (duration: 115.812µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:22.17022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.080002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:22.170272Z","caller":"traceutil/trace.go:171","msg":"trace[1395119936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"125.170294ms","start":"2026-04-28T06:43:22.045081Z","end":"2026-04-28T06:43:22.170251Z","steps":["trace[1395119936] 'agreement among raft nodes before linearized reading' (duration: 125.03307ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:22.170522Z","caller":"traceutil/trace.go:171","msg":"trace[381157749] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"170.103142ms","start":"2026-04-28T06:43:22.000405Z","end":"2026-04-28T06:43:22.170508Z","steps":["trace[381157749] 'process raft request' (duration: 169.373785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:22.948984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.669424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:22.949062Z","caller":"traceutil/trace.go:171","msg":"trace[1020946292] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"132.793597ms","start":"2026-04-28T06:43:22.816252Z","end":"2026-04-28T06:43:22.949046Z","steps":["trace[1020946292] 'range keys from in-memory index tree' (duration: 132.606452ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:24.045033Z","caller":"traceutil/trace.go:171","msg":"trace[222821597] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"695.441649ms","start":"2026-04-28T06:43:23.349563Z","end":"2026-04-28T06:43:24.045005Z","steps":["trace[222821597] 'process raft request' (duration: 694.860866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.045193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:23.34953Z","time spent":"695.586821ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:24.354577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.671363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:24.354723Z","caller":"traceutil/trace.go:171","msg":"trace[500864525] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"261.46293ms","start":"2026-04-28T06:43:24.09321Z","end":"2026-04-28T06:43:24.354673Z","steps":["trace[500864525] 'process raft request' (duration: 113.641514ms)","trace[500864525] 'compare' (duration: 147.55073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:24.963059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.997316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:43:24.963146Z","caller":"traceutil/trace.go:171","msg":"trace[1488184928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"483.128579ms","start":"2026-04-28T06:43:24.479999Z","end":"2026-04-28T06:43:24.963128Z","steps":["trace[1488184928] 'range keys from in-memory index tree' (duration: 482.839403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.963158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.884579ms","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-28T06:43:24.963187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:24.479979Z","time spent":"483.199031ms","remote":"127.0.0.1:34172","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-28T06:43:24.96324Z","caller":"traceutil/trace.go:171","msg":"trace[702973912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"484.960592ms","start":"2026-04-28T06:43:24.478235Z","end":"2026-04-28T06:43:24.963195Z","steps":["trace[702973912] 'range keys from in-memory index tree' (duration: 484.742726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.963309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:24.47822Z","time spent":"485.052044ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:43:24.964555Z","caller":"traceutil/trace.go:171","msg":"trace[1056996031] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"169.421647ms","start":"2026-04-28T06:43:24.795123Z","end":"2026-04-28T06:43:24.964545Z","steps":["trace[1056996031] 'process raft request' (duration: 158.97976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:25.139667Z","caller":"traceutil/trace.go:171","msg":"trace[1655017086] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"119.753411ms","start":"2026-04-28T06:43:25.019881Z","end":"2026-04-28T06:43:25.139635Z","steps":["trace[1655017086] 'process raft request' (duration: 87.9049ms)","trace[1655017086] 'compare' (duration: 31.725488ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:25.651717Z","caller":"traceutil/trace.go:171","msg":"trace[665891044] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"110.838129ms","start":"2026-04-28T06:43:25.540854Z","end":"2026-04-28T06:43:25.651692Z","steps":["trace[665891044] 'process raft request' (duration: 110.299537ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:26.833549Z","caller":"traceutil/trace.go:171","msg":"trace[1936744028] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"139.487909ms","start":"2026-04-28T06:43:26.694043Z","end":"2026-04-28T06:43:26.833531Z","steps":["trace[1936744028] 'process raft request' (duration: 138.732202ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:26.999428Z","caller":"traceutil/trace.go:171","msg":"trace[1748178566] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"107.34943ms","start":"2026-04-28T06:43:26.891835Z","end":"2026-04-28T06:43:26.999184Z","steps":["trace[1748178566] 'process raft request' (duration: 105.366326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:28.17212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:27.21897Z","time spent":"953.141222ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:43:28.173038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.521905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:28.173356Z","caller":"traceutil/trace.go:171","msg":"trace[1953625736] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"794.232893ms","start":"2026-04-28T06:43:27.37909Z","end":"2026-04-28T06:43:28.173323Z","steps":["trace[1953625736] 'process raft request' (duration: 678.315959ms)","trace[1953625736] 'compare' (duration: 115.108046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:28.17353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:27.379062Z","time spent":"794.380157ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:28.431789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.624977ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:43:28.432501Z","caller":"traceutil/trace.go:171","msg":"trace[1066681334] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"204.837148ms","start":"2026-04-28T06:43:28.22763Z","end":"2026-04-28T06:43:28.432468Z","steps":["trace[1066681334] 'process raft request' (duration: 204.669114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:28.663775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.757965ms","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-28T06:43:28.663868Z","caller":"traceutil/trace.go:171","msg":"trace[267344358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:612; }","duration":"128.877248ms","start":"2026-04-28T06:43:28.534971Z","end":"2026-04-28T06:43:28.663848Z","steps":["trace[267344358] 'range keys from in-memory index tree' (duration: 128.710644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:28.663892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.706874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:28.66399Z","caller":"traceutil/trace.go:171","msg":"trace[1846909567] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"218.843406ms","start":"2026-04-28T06:43:28.445132Z","end":"2026-04-28T06:43:28.663975Z","steps":["trace[1846909567] 'agreement among raft nodes before linearized reading' (duration: 218.702473ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:28.663781Z","caller":"traceutil/trace.go:171","msg":"trace[1716164826] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"218.587939ms","start":"2026-04-28T06:43:28.445164Z","end":"2026-04-28T06:43:28.663752Z","steps":["trace[1716164826] 'read index received' (duration: 44.381815ms)","trace[1716164826] 'applied index is now lower than readState.Index' (duration: 174.204464ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:28.664151Z","caller":"traceutil/trace.go:171","msg":"trace[1288513755] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"225.522527ms","start":"2026-04-28T06:43:28.438584Z","end":"2026-04-28T06:43:28.664107Z","steps":["trace[1288513755] 'process raft request' (duration: 146.799155ms)","trace[1288513755] 'compare' (duration: 78.22577ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:29.341574Z","caller":"traceutil/trace.go:171","msg":"trace[1864920061] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"241.386756ms","start":"2026-04-28T06:43:29.10015Z","end":"2026-04-28T06:43:29.341537Z","steps":["trace[1864920061] 'process raft request' (duration: 240.946006ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:29.35061Z","caller":"traceutil/trace.go:171","msg":"trace[49072302] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"118.485674ms","start":"2026-04-28T06:43:29.232105Z","end":"2026-04-28T06:43:29.35059Z","steps":["trace[49072302] 'read index received' (duration: 109.011468ms)","trace[49072302] 'applied index is now lower than readState.Index' (duration: 9.473336ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:29.350811Z","caller":"traceutil/trace.go:171","msg":"trace[1180038154] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"229.401474ms","start":"2026-04-28T06:43:29.121401Z","end":"2026-04-28T06:43:29.350802Z","steps":["trace[1180038154] 'process raft request' (duration: 229.038216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:29.350966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.841072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:29.350999Z","caller":"traceutil/trace.go:171","msg":"trace[895510805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:616; }","duration":"118.969325ms","start":"2026-04-28T06:43:29.232022Z","end":"2026-04-28T06:43:29.350991Z","steps":["trace[895510805] 'agreement among raft nodes before linearized reading' (duration: 118.905093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:29.352168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.027248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:29.352226Z","caller":"traceutil/trace.go:171","msg":"trace[499919920] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:616; }","duration":"120.12476ms","start":"2026-04-28T06:43:29.232089Z","end":"2026-04-28T06:43:29.352214Z","steps":["trace[499919920] 'agreement among raft nodes before linearized reading' (duration: 120.017387ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:29.539507Z","caller":"traceutil/trace.go:171","msg":"trace[1461461851] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"120.456098ms","start":"2026-04-28T06:43:29.419029Z","end":"2026-04-28T06:43:29.539486Z","steps":["trace[1461461851] 'read index received' (duration: 109.06075ms)","trace[1461461851] 'applied index is now lower than readState.Index' (duration: 11.392417ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:29.539663Z","caller":"traceutil/trace.go:171","msg":"trace[912015937] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"134.931245ms","start":"2026-04-28T06:43:29.404668Z","end":"2026-04-28T06:43:29.539599Z","steps":["trace[912015937] 'process raft request' (duration: 123.291572ms)","trace[912015937] 'compare' (duration: 11.350487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:29.539778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.722963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:43:29.540167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.490949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:29.540208Z","caller":"traceutil/trace.go:171","msg":"trace[70844277] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:617; }","duration":"120.56287ms","start":"2026-04-28T06:43:29.419635Z","end":"2026-04-28T06:43:29.540198Z","steps":["trace[70844277] 'agreement among raft nodes before linearized reading' (duration: 120.512339ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:29.540213Z","caller":"traceutil/trace.go:171","msg":"trace[1723235615] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:617; }","duration":"121.187524ms","start":"2026-04-28T06:43:29.418998Z","end":"2026-04-28T06:43:29.540185Z","steps":["trace[1723235615] 'agreement among raft nodes before linearized reading' (duration: 120.693503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:29.53979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.695363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:29.540408Z","caller":"traceutil/trace.go:171","msg":"trace[1192483621] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:617; }","duration":"121.332727ms","start":"2026-04-28T06:43:29.419066Z","end":"2026-04-28T06:43:29.540399Z","steps":["trace[1192483621] 'agreement among raft nodes before linearized reading' (duration: 120.687583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:31.67151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.9724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T06:43:31.671597Z","caller":"traceutil/trace.go:171","msg":"trace[2067856510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"314.120872ms","start":"2026-04-28T06:43:31.357456Z","end":"2026-04-28T06:43:31.671577Z","steps":["trace[2067856510] 'range keys from in-memory index tree' (duration: 313.728704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:31.671655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:31.357436Z","time spent":"314.209375ms","remote":"127.0.0.1:34172","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-28T06:43:34.088034Z","caller":"traceutil/trace.go:171","msg":"trace[1166105957] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"104.22791ms","start":"2026-04-28T06:43:33.98379Z","end":"2026-04-28T06:43:34.088018Z","steps":["trace[1166105957] 'process raft request' (duration: 104.130118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:45.240567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608867422,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:43:45.377613Z","caller":"traceutil/trace.go:171","msg":"trace[811480409] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"637.117277ms","start":"2026-04-28T06:43:44.740473Z","end":"2026-04-28T06:43:45.37759Z","steps":["trace[811480409] 'read index received' (duration: 636.931303ms)","trace[811480409] 'applied index is now lower than readState.Index' (duration: 185.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:45.377673Z","caller":"traceutil/trace.go:171","msg":"trace[288480994] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"792.026604ms","start":"2026-04-28T06:43:44.585612Z","end":"2026-04-28T06:43:45.377639Z","steps":["trace[288480994] 'process raft request' (duration: 791.84092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:45.377846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.361662ms","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-28T06:43:45.377846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:44.585596Z","time spent":"792.162327ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:45.377883Z","caller":"traceutil/trace.go:171","msg":"trace[2096095344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"637.461054ms","start":"2026-04-28T06:43:44.74041Z","end":"2026-04-28T06:43:45.377871Z","steps":["trace[2096095344] 'agreement among raft nodes before linearized reading' (duration: 637.312951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:45.37792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:44.740381Z","time spent":"637.530186ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:43:45.3779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.411067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:45.377969Z","caller":"traceutil/trace.go:171","msg":"trace[1842127309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"332.525419ms","start":"2026-04-28T06:43:45.045427Z","end":"2026-04-28T06:43:45.377953Z","steps":["trace[1842127309] 'agreement among raft nodes before linearized reading' (duration: 332.418787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:45.37804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:45.045402Z","time spent":"332.629562ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:45.378391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.183533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:45.378514Z","caller":"traceutil/trace.go:171","msg":"trace[105285901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"196.333035ms","start":"2026-04-28T06:43:45.182163Z","end":"2026-04-28T06:43:45.378496Z","steps":["trace[105285901] 'agreement among raft nodes before linearized reading' (duration: 196.021608ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:45.538857Z","caller":"traceutil/trace.go:171","msg":"trace[933914771] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"155.835478ms","start":"2026-04-28T06:43:45.382993Z","end":"2026-04-28T06:43:45.538828Z","steps":["trace[933914771] 'process raft request' (duration: 134.26839ms)","trace[933914771] 'compare' (duration: 21.161989ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:45.539044Z","caller":"traceutil/trace.go:171","msg":"trace[1398476918] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"155.616933ms","start":"2026-04-28T06:43:45.383418Z","end":"2026-04-28T06:43:45.539035Z","steps":["trace[1398476918] 'process raft request' (duration: 155.336877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.616166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608867459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:48.710233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.723591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:48.710303Z","caller":"traceutil/trace.go:171","msg":"trace[557798269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"664.851075ms","start":"2026-04-28T06:43:48.045438Z","end":"2026-04-28T06:43:48.710289Z","steps":["trace[557798269] 'range keys from in-memory index tree' (duration: 664.633249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.710346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:48.045422Z","time spent":"664.915116ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:48.710625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.692546ms","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-28T06:43:48.710753Z","caller":"traceutil/trace.go:171","msg":"trace[825229332] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:785; }","duration":"174.833839ms","start":"2026-04-28T06:43:48.5359Z","end":"2026-04-28T06:43:48.710733Z","steps":["trace[825229332] 'range keys from in-memory index tree' (duration: 174.676396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.719282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.480994ms","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-28T06:43:48.719334Z","caller":"traceutil/trace.go:171","msg":"trace[1126472127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"773.577246ms","start":"2026-04-28T06:43:47.945745Z","end":"2026-04-28T06:43:48.719322Z","steps":["trace[1126472127] 'range keys from in-memory index tree' (duration: 773.30525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.719366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:47.945719Z","time spent":"773.639198ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:43:48.870409Z","caller":"traceutil/trace.go:171","msg":"trace[1499437651] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"615.250592ms","start":"2026-04-28T06:43:48.255135Z","end":"2026-04-28T06:43:48.870385Z","steps":["trace[1499437651] 'process raft request' (duration: 615.033197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.870644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:48.25511Z","time spent":"615.451567ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:48.870826Z","caller":"traceutil/trace.go:171","msg":"trace[1868008799] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"148.342909ms","start":"2026-04-28T06:43:48.72245Z","end":"2026-04-28T06:43:48.870793Z","steps":["trace[1868008799] 'process raft request' (duration: 147.883869ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:48.870815Z","caller":"traceutil/trace.go:171","msg":"trace[273517811] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:813; }","duration":"754.67812ms","start":"2026-04-28T06:43:48.115872Z","end":"2026-04-28T06:43:48.87055Z","steps":["trace[273517811] 'read index received' (duration: 667.062015ms)","trace[273517811] 'applied index is now lower than readState.Index' (duration: 87.614585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:48.871673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.622933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:48.871742Z","caller":"traceutil/trace.go:171","msg":"trace[919105562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"151.736686ms","start":"2026-04-28T06:43:48.719989Z","end":"2026-04-28T06:43:48.871726Z","steps":["trace[919105562] 'agreement among raft nodes before linearized reading' (duration: 151.606193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.873535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.657317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:43:48.873597Z","caller":"traceutil/trace.go:171","msg":"trace[1176312084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"757.767849ms","start":"2026-04-28T06:43:48.115816Z","end":"2026-04-28T06:43:48.873584Z","steps":["trace[1176312084] 'agreement among raft nodes before linearized reading' (duration: 755.056688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:48.87364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:48.115797Z","time spent":"757.83468ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:45:55.926664Z","caller":"traceutil/trace.go:171","msg":"trace[1185651893] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"346.42784ms","start":"2026-04-28T06:45:55.580214Z","end":"2026-04-28T06:45:55.926642Z","steps":["trace[1185651893] 'process raft request' (duration: 346.297427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:45:55.926816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:45:55.5802Z","time spent":"346.535141ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:45:55.93881Z","caller":"traceutil/trace.go:171","msg":"trace[602805810] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"156.271962ms","start":"2026-04-28T06:45:55.782512Z","end":"2026-04-28T06:45:55.938784Z","steps":["trace[602805810] 'process raft request' (duration: 156.151089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.468975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.998274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:46:34.469308Z","caller":"traceutil/trace.go:171","msg":"trace[568037396] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"202.545015ms","start":"2026-04-28T06:46:34.266748Z","end":"2026-04-28T06:46:34.469293Z","steps":["trace[568037396] 'process raft request' (duration: 72.151422ms)","trace[568037396] 'compare' (duration: 129.79714ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:34.755391Z","caller":"traceutil/trace.go:171","msg":"trace[1151957721] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"184.202815ms","start":"2026-04-28T06:46:34.571157Z","end":"2026-04-28T06:46:34.75536Z","steps":["trace[1151957721] 'read index received' (duration: 184.041562ms)","trace[1151957721] 'applied index is now lower than readState.Index' (duration: 160.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:46:34.755455Z","caller":"traceutil/trace.go:171","msg":"trace[108469139] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"200.055169ms","start":"2026-04-28T06:46:34.555368Z","end":"2026-04-28T06:46:34.755423Z","steps":["trace[108469139] 'process raft request' (duration: 199.851255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:46:34.755601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.4302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:46:34.755669Z","caller":"traceutil/trace.go:171","msg":"trace[608595659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1431; }","duration":"184.554573ms","start":"2026-04-28T06:46:34.571103Z","end":"2026-04-28T06:46:34.755658Z","steps":["trace[608595659] 'agreement among raft nodes before linearized reading' (duration: 184.362088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:42.252257Z","caller":"traceutil/trace.go:171","msg":"trace[1649393872] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"108.610615ms","start":"2026-04-28T06:46:42.143623Z","end":"2026-04-28T06:46:42.252233Z","steps":["trace[1649393872] 'process raft request' (duration: 108.468682ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:46:47.00365Z","caller":"traceutil/trace.go:171","msg":"trace[2054521518] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"109.290721ms","start":"2026-04-28T06:46:46.894336Z","end":"2026-04-28T06:46:47.003626Z","steps":["trace[2054521518] 'process raft request' (duration: 101.096408ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:10.400922Z","caller":"traceutil/trace.go:171","msg":"trace[995876365] linearizableReadLoop","detail":"{readStateIndex:1616; appliedIndex:1615; }","duration":"269.577734ms","start":"2026-04-28T06:47:10.131323Z","end":"2026-04-28T06:47:10.400901Z","steps":["trace[995876365] 'read index received' (duration: 269.366359ms)","trace[995876365] 'applied index is now lower than readState.Index' (duration: 210.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:10.401013Z","caller":"traceutil/trace.go:171","msg":"trace[2016811981] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"285.474518ms","start":"2026-04-28T06:47:10.115508Z","end":"2026-04-28T06:47:10.400983Z","steps":["trace[2016811981] 'process raft request' (duration: 285.239962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:10.401155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.812689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T06:47:10.401199Z","caller":"traceutil/trace.go:171","msg":"trace[7814168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1545; }","duration":"269.88767ms","start":"2026-04-28T06:47:10.131301Z","end":"2026-04-28T06:47:10.401189Z","steps":["trace[7814168] 'agreement among raft nodes before linearized reading' (duration: 269.688866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:10.993022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608869371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:47:11.493584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608869371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:47:11.994699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608869371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:47:12.07314Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.629608727s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:47:12.073508Z","caller":"traceutil/trace.go:171","msg":"trace[1373929267] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"1.668976286s","start":"2026-04-28T06:47:10.404505Z","end":"2026-04-28T06:47:12.073481Z","steps":["trace[1373929267] 'process raft request' (duration: 1.668801032s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.073667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:10.404484Z","time spent":"1.669105879s","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:12.146864Z","caller":"traceutil/trace.go:171","msg":"trace[712848972] linearizableReadLoop","detail":"{readStateIndex:1618; appliedIndex:1616; }","duration":"1.654257248s","start":"2026-04-28T06:47:10.492585Z","end":"2026-04-28T06:47:12.146842Z","steps":["trace[712848972] 'read index received' (duration: 1.580815228s)","trace[712848972] 'applied index is now lower than readState.Index' (duration: 73.44115ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:12.146949Z","caller":"traceutil/trace.go:171","msg":"trace[1295347213] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"1.735786549s","start":"2026-04-28T06:47:10.411122Z","end":"2026-04-28T06:47:12.146909Z","steps":["trace[1295347213] 'process raft request' (duration: 1.733730203s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654494813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T06:47:12.147101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:10.411109Z","time spent":"1.735906852s","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:12.147127Z","caller":"traceutil/trace.go:171","msg":"trace[1703037999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1547; }","duration":"1.654588045s","start":"2026-04-28T06:47:10.492529Z","end":"2026-04-28T06:47:12.147117Z","steps":["trace[1703037999] 'agreement among raft nodes before linearized reading' (duration: 1.654444512s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:10.492509Z","time spent":"1.654645516s","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:47:12.147185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408770943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:47:12.147256Z","caller":"traceutil/trace.go:171","msg":"trace[1088263515] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1547; }","duration":"1.408852405s","start":"2026-04-28T06:47:10.738384Z","end":"2026-04-28T06:47:12.147237Z","steps":["trace[1088263515] 'agreement among raft nodes before linearized reading' (duration: 1.408736162s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:10.738317Z","time spent":"1.408971168s","remote":"127.0.0.1:50738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:12.147364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.953014ms","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-28T06:47:12.14741Z","caller":"traceutil/trace.go:171","msg":"trace[1829977988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1547; }","duration":"980.025135ms","start":"2026-04-28T06:47:11.167377Z","end":"2026-04-28T06:47:12.147402Z","steps":["trace[1829977988] 'agreement among raft nodes before linearized reading' (duration: 979.926483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.167362Z","time spent":"980.067616ms","remote":"127.0.0.1:34172","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-28T06:47:12.147596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074586358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:47:12.147638Z","caller":"traceutil/trace.go:171","msg":"trace[265403304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1547; }","duration":"1.074649278s","start":"2026-04-28T06:47:11.072979Z","end":"2026-04-28T06:47:12.147629Z","steps":["trace[265403304] 'agreement among raft nodes before linearized reading' (duration: 1.074496815s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.457326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:47:12.147651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382985938s","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-28T06:47:12.147667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.072969Z","time spent":"1.074689569s","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:47:12.147695Z","caller":"traceutil/trace.go:171","msg":"trace[1333878179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1547; }","duration":"1.383052349s","start":"2026-04-28T06:47:10.764632Z","end":"2026-04-28T06:47:12.147684Z","steps":["trace[1333878179] 'agreement among raft nodes before linearized reading' (duration: 1.382899035s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:10.764619Z","time spent":"1.38311133s","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:47:12.147831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10245456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:47:12.147865Z","caller":"traceutil/trace.go:171","msg":"trace[22362089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"1.10251975s","start":"2026-04-28T06:47:11.045335Z","end":"2026-04-28T06:47:12.147855Z","steps":["trace[22362089] 'agreement among raft nodes before linearized reading' (duration: 1.102475129s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.147892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.045317Z","time spent":"1.102569202s","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:47:12.147739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.992975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:47:12.14801Z","caller":"traceutil/trace.go:171","msg":"trace[618465420] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1547; }","duration":"540.28973ms","start":"2026-04-28T06:47:11.607705Z","end":"2026-04-28T06:47:12.147994Z","steps":["trace[618465420] 'agreement among raft nodes before linearized reading' (duration: 540.013784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:12.147682Z","caller":"traceutil/trace.go:171","msg":"trace[2036366637] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1547; }","duration":"378.532246ms","start":"2026-04-28T06:47:11.769138Z","end":"2026-04-28T06:47:12.147671Z","steps":["trace[2036366637] 'agreement among raft nodes before linearized reading' (duration: 378.476475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:12.148052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.607687Z","time spent":"540.352102ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:47:12.14807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:11.769118Z","time spent":"378.939396ms","remote":"127.0.0.1:54988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:47:50.515368Z","caller":"traceutil/trace.go:171","msg":"trace[909301395] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1804; }","duration":"182.303858ms","start":"2026-04-28T06:47:50.332971Z","end":"2026-04-28T06:47:50.515275Z","steps":["trace[909301395] 'read index received' (duration: 181.396247ms)","trace[909301395] 'applied index is now lower than readState.Index' (duration: 906.741µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:50.515486Z","caller":"traceutil/trace.go:171","msg":"trace[949809006] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"189.91346ms","start":"2026-04-28T06:47:50.325562Z","end":"2026-04-28T06:47:50.515476Z","steps":["trace[949809006] 'process raft request' (duration: 189.565332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:50.515693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.706516ms","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-28T06:47:50.515734Z","caller":"traceutil/trace.go:171","msg":"trace[990430897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1724; }","duration":"182.781089ms","start":"2026-04-28T06:47:50.332944Z","end":"2026-04-28T06:47:50.515725Z","steps":["trace[990430897] 'agreement among raft nodes before linearized reading' (duration: 182.650226ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:51.12203Z","caller":"traceutil/trace.go:171","msg":"trace[1007223494] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"600.489349ms","start":"2026-04-28T06:47:50.521402Z","end":"2026-04-28T06:47:51.121892Z","steps":["trace[1007223494] 'process raft request' (duration: 600.355936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.122213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.521381Z","time spent":"600.717273ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:51.123095Z","caller":"traceutil/trace.go:171","msg":"trace[1605382555] linearizableReadLoop","detail":"{readStateIndex:1810; appliedIndex:1805; }","duration":"472.324195ms","start":"2026-04-28T06:47:50.650751Z","end":"2026-04-28T06:47:51.123076Z","steps":["trace[1605382555] 'read index received' (duration: 470.994985ms)","trace[1605382555] 'applied index is now lower than readState.Index' (duration: 1.3284ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:51.123321Z","caller":"traceutil/trace.go:171","msg":"trace[1280922688] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"600.767723ms","start":"2026-04-28T06:47:50.522542Z","end":"2026-04-28T06:47:51.12331Z","steps":["trace[1280922688] 'process raft request' (duration: 600.377035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.123833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.522523Z","time spent":"600.830235ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:51.123998Z","caller":"traceutil/trace.go:171","msg":"trace[209546105] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"601.366717ms","start":"2026-04-28T06:47:50.52262Z","end":"2026-04-28T06:47:51.123987Z","steps":["trace[209546105] 'process raft request' (duration: 600.365925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.124954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.522612Z","time spent":"602.291479ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:51.125067Z","caller":"traceutil/trace.go:171","msg":"trace[444727189] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"602.204657ms","start":"2026-04-28T06:47:50.522832Z","end":"2026-04-28T06:47:51.125037Z","steps":["trace[444727189] 'process raft request' (duration: 600.185651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.125212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.522824Z","time spent":"602.314829ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:47:51.125336Z","caller":"traceutil/trace.go:171","msg":"trace[879825608] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"602.428892ms","start":"2026-04-28T06:47:50.522893Z","end":"2026-04-28T06:47:51.125322Z","steps":["trace[879825608] 'process raft request' (duration: 600.15386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.125416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.522886Z","time spent":"602.492513ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:47:51.125528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.78249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:47:51.125577Z","caller":"traceutil/trace.go:171","msg":"trace[925924130] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1729; }","duration":"474.829962ms","start":"2026-04-28T06:47:50.650733Z","end":"2026-04-28T06:47:51.125563Z","steps":["trace[925924130] 'agreement among raft nodes before linearized reading' (duration: 474.75413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:47:51.125624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:50.650687Z","time spent":"474.924894ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:48:05.236075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.326117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:05.236156Z","caller":"traceutil/trace.go:171","msg":"trace[240195246] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"634.542468ms","start":"2026-04-28T06:48:04.6016Z","end":"2026-04-28T06:48:05.236142Z","steps":["trace[240195246] 'read index received' (duration: 338.988726ms)","trace[240195246] 'applied index is now lower than readState.Index' (duration: 295.552332ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:05.236227Z","caller":"traceutil/trace.go:171","msg":"trace[299729768] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"635.324487ms","start":"2026-04-28T06:48:04.600866Z","end":"2026-04-28T06:48:05.23619Z","steps":["trace[299729768] 'process raft request' (duration: 339.825636ms)","trace[299729768] 'compare' (duration: 295.01307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:05.236359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:04.600859Z","time spent":"635.437739ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:05.23636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.742222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-28T06:48:05.236422Z","caller":"traceutil/trace.go:171","msg":"trace[456601938] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1775; }","duration":"634.827705ms","start":"2026-04-28T06:48:04.601579Z","end":"2026-04-28T06:48:05.236407Z","steps":["trace[456601938] 'agreement among raft nodes before linearized reading' (duration: 634.642991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:05.236457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:04.601574Z","time spent":"634.876636ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T06:48:05.353424Z","caller":"traceutil/trace.go:171","msg":"trace[1029364910] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"111.948645ms","start":"2026-04-28T06:48:05.241317Z","end":"2026-04-28T06:48:05.353266Z","steps":["trace[1029364910] 'process raft request' (duration: 111.775991ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.354552Z","caller":"traceutil/trace.go:171","msg":"trace[62582465] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"111.907654ms","start":"2026-04-28T06:48:05.242618Z","end":"2026-04-28T06:48:05.354525Z","steps":["trace[62582465] 'process raft request' (duration: 111.627288ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.354719Z","caller":"traceutil/trace.go:171","msg":"trace[375667264] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"111.969395ms","start":"2026-04-28T06:48:05.242738Z","end":"2026-04-28T06:48:05.354707Z","steps":["trace[375667264] 'process raft request' (duration: 111.633798ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.354793Z","caller":"traceutil/trace.go:171","msg":"trace[1234891312] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"110.382599ms","start":"2026-04-28T06:48:05.244405Z","end":"2026-04-28T06:48:05.354787Z","steps":["trace[1234891312] 'process raft request' (duration: 110.156934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.354879Z","caller":"traceutil/trace.go:171","msg":"trace[619952051] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"110.242406ms","start":"2026-04-28T06:48:05.244631Z","end":"2026-04-28T06:48:05.354873Z","steps":["trace[619952051] 'process raft request' (duration: 109.95501ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:11.779627Z","caller":"traceutil/trace.go:171","msg":"trace[1756167261] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"391.294961ms","start":"2026-04-28T06:48:11.388308Z","end":"2026-04-28T06:48:11.779603Z","steps":["trace[1756167261] 'read index received' (duration: 391.197338ms)","trace[1756167261] 'applied index is now lower than readState.Index' (duration: 96.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:11.779714Z","caller":"traceutil/trace.go:171","msg":"trace[1094651604] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"391.478995ms","start":"2026-04-28T06:48:11.388197Z","end":"2026-04-28T06:48:11.779676Z","steps":["trace[1094651604] 'process raft request' (duration: 391.271421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:11.779906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.388178Z","time spent":"391.621328ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:11.780177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.603627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:48:11.780275Z","caller":"traceutil/trace.go:171","msg":"trace[1373010887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"391.970626ms","start":"2026-04-28T06:48:11.38828Z","end":"2026-04-28T06:48:11.780251Z","steps":["trace[1373010887] 'agreement among raft nodes before linearized reading' (duration: 391.485215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:11.780346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.388269Z","time spent":"392.065818ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:48:12.17613Z","caller":"traceutil/trace.go:171","msg":"trace[1575382274] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1917; }","duration":"396.368606ms","start":"2026-04-28T06:48:11.77973Z","end":"2026-04-28T06:48:12.176099Z","steps":["trace[1575382274] 'read index received' (duration: 347.744885ms)","trace[1575382274] 'applied index is now lower than readState.Index' (duration: 48.622771ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:12.176462Z","caller":"traceutil/trace.go:171","msg":"trace[664566847] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"785.676811ms","start":"2026-04-28T06:48:11.390754Z","end":"2026-04-28T06:48:12.176431Z","steps":["trace[664566847] 'process raft request' (duration: 785.127179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:12.176616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.390743Z","time spent":"785.794404ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:12.176675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.399841ms","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-28T06:48:12.176726Z","caller":"traceutil/trace.go:171","msg":"trace[927091563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1834; }","duration":"787.518603ms","start":"2026-04-28T06:48:11.389195Z","end":"2026-04-28T06:48:12.176714Z","steps":["trace[927091563] 'agreement among raft nodes before linearized reading' (duration: 787.320519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:12.17677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.389181Z","time spent":"787.580365ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:48:12.176912Z","caller":"traceutil/trace.go:171","msg":"trace[2113685638] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"786.772866ms","start":"2026-04-28T06:48:11.38987Z","end":"2026-04-28T06:48:12.176642Z","steps":["trace[2113685638] 'process raft request' (duration: 737.629042ms)","trace[2113685638] 'compare' (duration: 48.218852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:12.177356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.389852Z","time spent":"787.407831ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:12.441077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.861188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:12.441263Z","caller":"traceutil/trace.go:171","msg":"trace[1526475444] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"264.992881ms","start":"2026-04-28T06:48:12.176242Z","end":"2026-04-28T06:48:12.441234Z","steps":["trace[1526475444] 'read index received' (duration: 120.860998ms)","trace[1526475444] 'applied index is now lower than readState.Index' (duration: 144.128073ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:12.441302Z","caller":"traceutil/trace.go:171","msg":"trace[1817748528] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"224.408591ms","start":"2026-04-28T06:48:12.216879Z","end":"2026-04-28T06:48:12.441288Z","steps":["trace[1817748528] 'process raft request' (duration: 224.36546ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:12.441343Z","caller":"traceutil/trace.go:171","msg":"trace[129683127] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"259.416585ms","start":"2026-04-28T06:48:12.181897Z","end":"2026-04-28T06:48:12.441314Z","steps":["trace[129683127] 'process raft request' (duration: 259.297672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:12.441224Z","caller":"traceutil/trace.go:171","msg":"trace[2022930732] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"656.752842ms","start":"2026-04-28T06:48:11.784444Z","end":"2026-04-28T06:48:12.441197Z","steps":["trace[2022930732] 'process raft request' (duration: 512.68651ms)","trace[2022930732] 'compare' (duration: 143.747075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:12.441462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.678106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:48:12.441494Z","caller":"traceutil/trace.go:171","msg":"trace[197439623] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1837; }","duration":"390.780319ms","start":"2026-04-28T06:48:12.050707Z","end":"2026-04-28T06:48:12.441487Z","steps":["trace[197439623] 'agreement among raft nodes before linearized reading' (duration: 390.660586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:12.441525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:12.050678Z","time spent":"390.83847ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":14,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:48:12.441642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:11.784423Z","time spent":"657.055858ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:12.441715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.907866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:48:12.441798Z","caller":"traceutil/trace.go:171","msg":"trace[771760763] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1837; }","duration":"143.036219ms","start":"2026-04-28T06:48:12.298743Z","end":"2026-04-28T06:48:12.441779Z","steps":["trace[771760763] 'agreement among raft nodes before linearized reading' (duration: 142.880196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:12.835156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.593133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:12.835336Z","caller":"traceutil/trace.go:171","msg":"trace[552106756] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"198.639858ms","start":"2026-04-28T06:48:12.636682Z","end":"2026-04-28T06:48:12.835322Z","steps":["trace[552106756] 'process raft request' (duration: 198.579437ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:12.835339Z","caller":"traceutil/trace.go:171","msg":"trace[1543561686] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"317.0881ms","start":"2026-04-28T06:48:12.518214Z","end":"2026-04-28T06:48:12.835302Z","steps":["trace[1543561686] 'process raft request' (duration: 29.286232ms)","trace[1543561686] 'compare' (duration: 287.408389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:12.835443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:12.518186Z","time spent":"317.208244ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:12.835526Z","caller":"traceutil/trace.go:171","msg":"trace[1103621369] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"230.1087ms","start":"2026-04-28T06:48:12.605408Z","end":"2026-04-28T06:48:12.835517Z","steps":["trace[1103621369] 'read index received' (duration: 50.971µs)","trace[1103621369] 'applied index is now lower than readState.Index' (duration: 230.053659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:12.835944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.51974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-28T06:48:12.836049Z","caller":"traceutil/trace.go:171","msg":"trace[1042362631] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1839; }","duration":"230.675244ms","start":"2026-04-28T06:48:12.605347Z","end":"2026-04-28T06:48:12.836022Z","steps":["trace[1042362631] 'agreement among raft nodes before linearized reading' (duration: 230.398627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:12.836114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.205312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:48:12.836187Z","caller":"traceutil/trace.go:171","msg":"trace[207810989] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1839; }","duration":"226.314495ms","start":"2026-04-28T06:48:12.609856Z","end":"2026-04-28T06:48:12.83617Z","steps":["trace[207810989] 'agreement among raft nodes before linearized reading' (duration: 226.10599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:13.187547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:12.837542Z","time spent":"349.998966ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:48:13.59413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.94087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:13.594326Z","caller":"traceutil/trace.go:171","msg":"trace[1161057875] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"405.509423ms","start":"2026-04-28T06:48:13.1888Z","end":"2026-04-28T06:48:13.594309Z","steps":["trace[1161057875] 'process raft request' (duration: 405.440432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:13.594392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:13.188774Z","time spent":"405.586964ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:13.594433Z","caller":"traceutil/trace.go:171","msg":"trace[988948953] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"754.568767ms","start":"2026-04-28T06:48:12.839832Z","end":"2026-04-28T06:48:13.594401Z","steps":["trace[988948953] 'process raft request' (duration: 485.171307ms)","trace[988948953] 'compare' (duration: 268.812546ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:13.594503Z","caller":"traceutil/trace.go:171","msg":"trace[1221970909] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1924; }","duration":"550.247361ms","start":"2026-04-28T06:48:13.044245Z","end":"2026-04-28T06:48:13.594493Z","steps":["trace[1221970909] 'read index received' (duration: 78.863146ms)","trace[1221970909] 'applied index is now lower than readState.Index' (duration: 471.383325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:13.594614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:12.839814Z","time spent":"754.705831ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:13.594776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.527077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:48:13.594816Z","caller":"traceutil/trace.go:171","msg":"trace[1833481684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"550.601698ms","start":"2026-04-28T06:48:13.044205Z","end":"2026-04-28T06:48:13.594807Z","steps":["trace[1833481684] 'agreement among raft nodes before linearized reading' (duration: 550.483346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:13.594846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:13.044187Z","time spent":"550.65378ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:48:13.899662Z","caller":"traceutil/trace.go:171","msg":"trace[1995026176] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"100.836933ms","start":"2026-04-28T06:48:13.798808Z","end":"2026-04-28T06:48:13.899645Z","steps":["trace[1995026176] 'process raft request' (duration: 100.69798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.758384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.300338ms","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-28T06:48:30.758451Z","caller":"traceutil/trace.go:171","msg":"trace[1633776813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1964; }","duration":"220.39679ms","start":"2026-04-28T06:48:30.53804Z","end":"2026-04-28T06:48:30.758437Z","steps":["trace[1633776813] 'range keys from in-memory index tree' (duration: 220.171485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.758746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.812728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T06:48:30.758775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.05526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:48:30.758816Z","caller":"traceutil/trace.go:171","msg":"trace[585997686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1964; }","duration":"220.917111ms","start":"2026-04-28T06:48:30.53788Z","end":"2026-04-28T06:48:30.758797Z","steps":["trace[585997686] 'range keys from in-memory index tree' (duration: 220.665935ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:30.758827Z","caller":"traceutil/trace.go:171","msg":"trace[1916502888] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1964; }","duration":"264.158712ms","start":"2026-04-28T06:48:30.494652Z","end":"2026-04-28T06:48:30.758811Z","steps":["trace[1916502888] 'range keys from in-memory index tree' (duration: 263.990779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.265263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439696608870314,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:48:31.662516Z","caller":"traceutil/trace.go:171","msg":"trace[2079812378] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"897.794725ms","start":"2026-04-28T06:48:30.764694Z","end":"2026-04-28T06:48:31.662489Z","steps":["trace[2079812378] 'process raft request' (duration: 897.646292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.662814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.764675Z","time spent":"897.910028ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.720953Z","caller":"traceutil/trace.go:171","msg":"trace[656441306] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"955.276231ms","start":"2026-04-28T06:48:30.765662Z","end":"2026-04-28T06:48:31.720938Z","steps":["trace[656441306] 'process raft request' (duration: 955.184479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.721099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.765653Z","time spent":"955.361493ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.720935Z","caller":"traceutil/trace.go:171","msg":"trace[913733789] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"955.797742ms","start":"2026-04-28T06:48:30.765107Z","end":"2026-04-28T06:48:31.720905Z","steps":["trace[913733789] 'process raft request' (duration: 955.622518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.721242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.765082Z","time spent":"956.108999ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.721287Z","caller":"traceutil/trace.go:171","msg":"trace[1337364201] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2053; }","duration":"956.231872ms","start":"2026-04-28T06:48:30.765036Z","end":"2026-04-28T06:48:31.721268Z","steps":["trace[1337364201] 'read index received' (duration: 897.302295ms)","trace[1337364201] 'applied index is now lower than readState.Index' (duration: 58.928327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:31.721378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.329074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:48:31.721426Z","caller":"traceutil/trace.go:171","msg":"trace[1774106978] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"824.625675ms","start":"2026-04-28T06:48:30.896763Z","end":"2026-04-28T06:48:31.721388Z","steps":["trace[1774106978] 'process raft request' (duration: 824.141975ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:31.721663Z","caller":"traceutil/trace.go:171","msg":"trace[1529056289] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1968; }","duration":"956.665012ms","start":"2026-04-28T06:48:30.764989Z","end":"2026-04-28T06:48:31.721654Z","steps":["trace[1529056289] 'agreement among raft nodes before linearized reading' (duration: 956.328814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.723053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.76497Z","time spent":"958.069122ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-28T06:48:31.721846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.896735Z","time spent":"825.043236ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:32.500993Z","caller":"traceutil/trace.go:171","msg":"trace[531016008] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"237.733701ms","start":"2026-04-28T06:48:32.263205Z","end":"2026-04-28T06:48:32.500939Z","steps":["trace[531016008] 'process raft request' (duration: 237.595138ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:32.624986Z","caller":"traceutil/trace.go:171","msg":"trace[693187220] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"361.477744ms","start":"2026-04-28T06:48:32.26348Z","end":"2026-04-28T06:48:32.624958Z","steps":["trace[693187220] 'process raft request' (duration: 269.5298ms)","trace[693187220] 'compare' (duration: 91.640197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:32.625139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:32.263443Z","time spent":"361.624677ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:32.773258Z","caller":"traceutil/trace.go:171","msg":"trace[1287199972] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"142.345033ms","start":"2026-04-28T06:48:32.630887Z","end":"2026-04-28T06:48:32.773232Z","steps":["trace[1287199972] 'process raft request' (duration: 142.203149ms)"],"step_count":1}