{"level":"warn","ts":"2026-05-21T19:20:44.517692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:20:44.517837Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T19:20:44.517963Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:20:44.517988Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-21T19:20:44.518044Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:20:44.519143Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-21T19:20:44.519261Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T19:20:44.533829Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.279898ms"} {"level":"info","ts":"2026-05-21T19:20:44.622041Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-21T19:20:44.622148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:20:44.622173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-21T19:20:44.622189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:20:44.622202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-21T19:20:44.622245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-21T19:20:44.704635Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:20:44.713689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:20:44.729813Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T19:20:44.736734Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:20:44.736937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:20:44.737328Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:20:44.737395Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:20:44.737415Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:20:44.739981Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:20:44.740139Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:20:44.740351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:20:44.74045Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-21T19:20:44.740462Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-21T19:20:44.745268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-21T19:20:44.745378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-21T19:20:45.622902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:20:45.622962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:20:45.623002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-21T19:20:45.623022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:20:45.623034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-21T19:20:45.623053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-21T19:20:45.623067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-21T19:20:45.7636Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:20:45.765967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:20:45.765966Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:20:45.766017Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:20:45.766446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:20:45.766529Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:20:45.770706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-21T19:20:45.772263Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:20:45.775433Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:20:45.77561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:20:45.775659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:20:47.559064Z","caller":"traceutil/trace.go:171","msg":"trace[879117017] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"199.069912ms","start":"2026-05-21T19:20:47.359951Z","end":"2026-05-21T19:20:47.559021Z","steps":["trace[879117017] 'process raft request' (duration: 151.716048ms)","trace[879117017] 'compare' (duration: 47.131527ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:47.559789Z","caller":"traceutil/trace.go:171","msg":"trace[1651852946] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"174.490011ms","start":"2026-05-21T19:20:47.385278Z","end":"2026-05-21T19:20:47.559768Z","steps":["trace[1651852946] 'process raft request' (duration: 173.684345ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:47.719844Z","caller":"traceutil/trace.go:171","msg":"trace[2094952539] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:52; }","duration":"110.174599ms","start":"2026-05-21T19:20:47.60962Z","end":"2026-05-21T19:20:47.719794Z","steps":["trace[2094952539] 'read index received' (duration: 109.409105ms)","trace[2094952539] 'applied index is now lower than readState.Index' (duration: 763.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:47.719903Z","caller":"traceutil/trace.go:171","msg":"trace[1367564330] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"156.126488ms","start":"2026-05-21T19:20:47.563685Z","end":"2026-05-21T19:20:47.719812Z","steps":["trace[1367564330] 'process raft request' (duration: 155.463317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:47.720007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.359054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b1aae8437af9c0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:20:47.720077Z","caller":"traceutil/trace.go:171","msg":"trace[1760199539] range","detail":"{range_begin:/registry/events/default/instance.18b1aae8437af9c0; range_end:; response_count:0; response_revision:49; }","duration":"110.519769ms","start":"2026-05-21T19:20:47.609544Z","end":"2026-05-21T19:20:47.720064Z","steps":["trace[1760199539] 'agreement among raft nodes before linearized reading' (duration: 110.394465ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:47.720038Z","caller":"traceutil/trace.go:171","msg":"trace[368274511] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"155.088025ms","start":"2026-05-21T19:20:47.564871Z","end":"2026-05-21T19:20:47.719959Z","steps":["trace[368274511] 'process raft request' (duration: 154.852058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:52.990146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.875375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T19:20:52.990259Z","caller":"traceutil/trace.go:171","msg":"trace[1836050179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"132.03631ms","start":"2026-05-21T19:20:52.858193Z","end":"2026-05-21T19:20:52.990229Z","steps":["trace[1836050179] 'range keys from in-memory index tree' (duration: 131.783742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:53.291504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.772209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-21T19:20:53.291573Z","caller":"traceutil/trace.go:171","msg":"trace[1224360634] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:329; }","duration":"183.851912ms","start":"2026-05-21T19:20:53.107705Z","end":"2026-05-21T19:20:53.291557Z","steps":["trace[1224360634] 'range keys from in-memory index tree' (duration: 183.685527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:53.291621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.954548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T19:20:53.291707Z","caller":"traceutil/trace.go:171","msg":"trace[572606753] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"134.093132ms","start":"2026-05-21T19:20:53.157593Z","end":"2026-05-21T19:20:53.291686Z","steps":["trace[572606753] 'range keys from in-memory index tree' (duration: 133.820444ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:53.582563Z","caller":"traceutil/trace.go:171","msg":"trace[1214995844] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"174.177012ms","start":"2026-05-21T19:20:53.408293Z","end":"2026-05-21T19:20:53.58247Z","steps":["trace[1214995844] 'process raft request' (duration: 124.881347ms)","trace[1214995844] 'compare' (duration: 49.015567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:53.848422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.388451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:53.848623Z","caller":"traceutil/trace.go:171","msg":"trace[1753264427] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"261.506565ms","start":"2026-05-21T19:20:53.587101Z","end":"2026-05-21T19:20:53.848608Z","steps":["trace[1753264427] 'process raft request' (duration: 118.168966ms)","trace[1753264427] 'compare' (duration: 142.277808ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:53.84854Z","caller":"traceutil/trace.go:171","msg":"trace[1348517835] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"236.959511ms","start":"2026-05-21T19:20:53.611565Z","end":"2026-05-21T19:20:53.848525Z","steps":["trace[1348517835] 'read index received' (duration: 93.644262ms)","trace[1348517835] 'applied index is now lower than readState.Index' (duration: 143.314179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:53.848878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.26867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-21T19:20:53.849048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.361683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-21T19:20:53.849159Z","caller":"traceutil/trace.go:171","msg":"trace[574042415] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:333; }","duration":"237.510617ms","start":"2026-05-21T19:20:53.611632Z","end":"2026-05-21T19:20:53.849143Z","steps":["trace[574042415] 'agreement among raft nodes before linearized reading' (duration: 237.366123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:53.849273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.348566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-21T19:20:53.849304Z","caller":"traceutil/trace.go:171","msg":"trace[581648952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:333; }","duration":"191.414228ms","start":"2026-05-21T19:20:53.657881Z","end":"2026-05-21T19:20:53.849295Z","steps":["trace[581648952] 'agreement among raft nodes before linearized reading' (duration: 191.373107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:53.849323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.299361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-21T19:20:53.849086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.699451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-21T19:20:53.849397Z","caller":"traceutil/trace.go:171","msg":"trace[462796779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:333; }","duration":"141.068102ms","start":"2026-05-21T19:20:53.70832Z","end":"2026-05-21T19:20:53.849388Z","steps":["trace[462796779] 'agreement among raft nodes before linearized reading' (duration: 140.69002ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:53.849405Z","caller":"traceutil/trace.go:171","msg":"trace[527671346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:333; }","duration":"234.391854ms","start":"2026-05-21T19:20:53.614987Z","end":"2026-05-21T19:20:53.849379Z","steps":["trace[527671346] 'agreement among raft nodes before linearized reading' (duration: 234.282011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:53.849238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.444016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-21T19:20:53.849559Z","caller":"traceutil/trace.go:171","msg":"trace[1050765645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:333; }","duration":"229.748205ms","start":"2026-05-21T19:20:53.619759Z","end":"2026-05-21T19:20:53.849508Z","steps":["trace[1050765645] 'agreement among raft nodes before linearized reading' (duration: 229.451876ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:53.849086Z","caller":"traceutil/trace.go:171","msg":"trace[1288033713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:333; }","duration":"237.400324ms","start":"2026-05-21T19:20:53.611539Z","end":"2026-05-21T19:20:53.848939Z","steps":["trace[1288033713] 'agreement among raft nodes before linearized reading' (duration: 237.189928ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.07254Z","caller":"traceutil/trace.go:171","msg":"trace[1441997035] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"164.831552ms","start":"2026-05-21T19:20:53.907687Z","end":"2026-05-21T19:20:54.072519Z","steps":["trace[1441997035] 'read index received' (duration: 91.651361ms)","trace[1441997035] 'applied index is now lower than readState.Index' (duration: 73.17917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:54.07279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.061179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-21T19:20:54.072843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.677042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-21T19:20:54.072836Z","caller":"traceutil/trace.go:171","msg":"trace[1096979830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:334; }","duration":"165.191113ms","start":"2026-05-21T19:20:53.907633Z","end":"2026-05-21T19:20:54.072824Z","steps":["trace[1096979830] 'agreement among raft nodes before linearized reading' (duration: 165.063799ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.072896Z","caller":"traceutil/trace.go:171","msg":"trace[1076789739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:334; }","duration":"114.757434ms","start":"2026-05-21T19:20:53.958126Z","end":"2026-05-21T19:20:54.072883Z","steps":["trace[1076789739] 'agreement among raft nodes before linearized reading' (duration: 114.665742ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.072722Z","caller":"traceutil/trace.go:171","msg":"trace[2090088984] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"208.726955ms","start":"2026-05-21T19:20:53.863957Z","end":"2026-05-21T19:20:54.072684Z","steps":["trace[2090088984] 'process raft request' (duration: 135.330419ms)","trace[2090088984] 'compare' (duration: 73.127948ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.214649Z","caller":"traceutil/trace.go:171","msg":"trace[142882623] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"133.530607ms","start":"2026-05-21T19:20:54.081066Z","end":"2026-05-21T19:20:54.214597Z","steps":["trace[142882623] 'process raft request' (duration: 133.326751ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.214699Z","caller":"traceutil/trace.go:171","msg":"trace[1646395541] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"130.313012ms","start":"2026-05-21T19:20:54.084348Z","end":"2026-05-21T19:20:54.214661Z","steps":["trace[1646395541] 'process raft request' (duration: 130.139437ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.214794Z","caller":"traceutil/trace.go:171","msg":"trace[277775688] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"129.692764ms","start":"2026-05-21T19:20:54.085068Z","end":"2026-05-21T19:20:54.214761Z","steps":["trace[277775688] 'process raft request' (duration: 129.459457ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.214868Z","caller":"traceutil/trace.go:171","msg":"trace[589342206] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"133.896608ms","start":"2026-05-21T19:20:54.080926Z","end":"2026-05-21T19:20:54.214823Z","steps":["trace[589342206] 'process raft request' (duration: 92.111879ms)","trace[589342206] 'compare' (duration: 41.194741ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.215078Z","caller":"traceutil/trace.go:171","msg":"trace[1151065858] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"131.139766ms","start":"2026-05-21T19:20:54.083901Z","end":"2026-05-21T19:20:54.215041Z","steps":["trace[1151065858] 'process raft request' (duration: 130.532558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.214908Z","caller":"traceutil/trace.go:171","msg":"trace[49506067] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:349; }","duration":"107.374242ms","start":"2026-05-21T19:20:54.107329Z","end":"2026-05-21T19:20:54.214704Z","steps":["trace[49506067] 'read index received' (duration: 23.149956ms)","trace[49506067] 'applied index is now lower than readState.Index' (duration: 84.218906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:54.215375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.034881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-21T19:20:54.215459Z","caller":"traceutil/trace.go:171","msg":"trace[1945710850] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:341; }","duration":"108.161886ms","start":"2026-05-21T19:20:54.107277Z","end":"2026-05-21T19:20:54.215439Z","steps":["trace[1945710850] 'agreement among raft nodes before linearized reading' (duration: 108.018542ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410226Z","caller":"traceutil/trace.go:171","msg":"trace[551146358] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"187.469406ms","start":"2026-05-21T19:20:54.222725Z","end":"2026-05-21T19:20:54.410194Z","steps":["trace[551146358] 'process raft request' (duration: 187.312471ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410299Z","caller":"traceutil/trace.go:171","msg":"trace[2032174860] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"190.124865ms","start":"2026-05-21T19:20:54.220104Z","end":"2026-05-21T19:20:54.410229Z","steps":["trace[2032174860] 'process raft request' (duration: 133.386913ms)","trace[2032174860] 'compare' (duration: 56.373391ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.41053Z","caller":"traceutil/trace.go:171","msg":"trace[630846308] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"187.301061ms","start":"2026-05-21T19:20:54.223212Z","end":"2026-05-21T19:20:54.410513Z","steps":["trace[630846308] 'process raft request' (duration: 186.92434ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410625Z","caller":"traceutil/trace.go:171","msg":"trace[2074041051] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"187.577289ms","start":"2026-05-21T19:20:54.223033Z","end":"2026-05-21T19:20:54.41061Z","steps":["trace[2074041051] 'process raft request' (duration: 187.063364ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410709Z","caller":"traceutil/trace.go:171","msg":"trace[972388413] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"186.835058ms","start":"2026-05-21T19:20:54.223819Z","end":"2026-05-21T19:20:54.410654Z","steps":["trace[972388413] 'process raft request' (duration: 186.474317ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410917Z","caller":"traceutil/trace.go:171","msg":"trace[1176634516] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"188.712173ms","start":"2026-05-21T19:20:54.222192Z","end":"2026-05-21T19:20:54.410904Z","steps":["trace[1176634516] 'read index received' (duration: 37.09799ms)","trace[1176634516] 'applied index is now lower than readState.Index' (duration: 151.612663ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.410997Z","caller":"traceutil/trace.go:171","msg":"trace[476697803] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"148.535782ms","start":"2026-05-21T19:20:54.262441Z","end":"2026-05-21T19:20:54.410977Z","steps":["trace[476697803] 'process raft request' (duration: 148.284165ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.410932Z","caller":"traceutil/trace.go:171","msg":"trace[118676100] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"185.459236ms","start":"2026-05-21T19:20:54.225458Z","end":"2026-05-21T19:20:54.410918Z","steps":["trace[118676100] 'process raft request' (duration: 185.096496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:54.411184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.96209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-21T19:20:54.411273Z","caller":"traceutil/trace.go:171","msg":"trace[794164123] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:349; }","duration":"189.072784ms","start":"2026-05-21T19:20:54.222156Z","end":"2026-05-21T19:20:54.411229Z","steps":["trace[794164123] 'agreement among raft nodes before linearized reading' (duration: 188.870698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:54.41129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.8666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-21T19:20:54.411399Z","caller":"traceutil/trace.go:171","msg":"trace[1430687537] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:349; }","duration":"153.089117ms","start":"2026-05-21T19:20:54.258294Z","end":"2026-05-21T19:20:54.411383Z","steps":["trace[1430687537] 'agreement among raft nodes before linearized reading' (duration: 152.879781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:54.411467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.501227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T19:20:54.411514Z","caller":"traceutil/trace.go:171","msg":"trace[472330432] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:349; }","duration":"103.596521ms","start":"2026-05-21T19:20:54.307905Z","end":"2026-05-21T19:20:54.411501Z","steps":["trace[472330432] 'agreement among raft nodes before linearized reading' (duration: 103.525959ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.674325Z","caller":"traceutil/trace.go:171","msg":"trace[839768286] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"251.499674ms","start":"2026-05-21T19:20:54.422797Z","end":"2026-05-21T19:20:54.674297Z","steps":["trace[839768286] 'process raft request' (duration: 155.854509ms)","trace[839768286] 'compare' (duration: 95.092998ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.674668Z","caller":"traceutil/trace.go:171","msg":"trace[1329879673] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"251.136023ms","start":"2026-05-21T19:20:54.423498Z","end":"2026-05-21T19:20:54.674634Z","steps":["trace[1329879673] 'process raft request' (duration: 250.735951ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.674754Z","caller":"traceutil/trace.go:171","msg":"trace[971858594] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:365; }","duration":"216.916898ms","start":"2026-05-21T19:20:54.457809Z","end":"2026-05-21T19:20:54.674726Z","steps":["trace[971858594] 'read index received' (duration: 120.79259ms)","trace[971858594] 'applied index is now lower than readState.Index' (duration: 96.123358ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:54.674996Z","caller":"traceutil/trace.go:171","msg":"trace[559115531] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"220.434213ms","start":"2026-05-21T19:20:54.454546Z","end":"2026-05-21T19:20:54.674981Z","steps":["trace[559115531] 'process raft request' (duration: 220.136024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:54.675144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.3179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T19:20:54.675226Z","caller":"traceutil/trace.go:171","msg":"trace[1416045467] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:354; }","duration":"217.413423ms","start":"2026-05-21T19:20:54.4578Z","end":"2026-05-21T19:20:54.675213Z","steps":["trace[1416045467] 'agreement among raft nodes before linearized reading' (duration: 217.218207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:54.675385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.504684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T19:20:54.675463Z","caller":"traceutil/trace.go:171","msg":"trace[1211917504] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:354; }","duration":"166.611778ms","start":"2026-05-21T19:20:54.508832Z","end":"2026-05-21T19:20:54.675444Z","steps":["trace[1211917504] 'agreement among raft nodes before linearized reading' (duration: 166.184135ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:54.674732Z","caller":"traceutil/trace.go:171","msg":"trace[23305050] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"247.055791ms","start":"2026-05-21T19:20:54.427661Z","end":"2026-05-21T19:20:54.674717Z","steps":["trace[23305050] 'process raft request' (duration: 246.897966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:55.188597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.584824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:55.188873Z","caller":"traceutil/trace.go:171","msg":"trace[292455421] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"432.823165ms","start":"2026-05-21T19:20:54.756032Z","end":"2026-05-21T19:20:55.188855Z","steps":["trace[292455421] 'process raft request' (duration: 432.782424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:55.188948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:54.756012Z","time spent":"432.909698ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:20:55.189024Z","caller":"traceutil/trace.go:171","msg":"trace[899009755] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"505.473868ms","start":"2026-05-21T19:20:54.683516Z","end":"2026-05-21T19:20:55.18899Z","steps":["trace[899009755] 'process raft request' (duration: 279.40426ms)","trace[899009755] 'compare' (duration: 225.42914ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:55.189095Z","caller":"traceutil/trace.go:171","msg":"trace[1718465299] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"504.578542ms","start":"2026-05-21T19:20:54.684424Z","end":"2026-05-21T19:20:55.189003Z","steps":["trace[1718465299] 'process raft request' (duration: 504.318984ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:55.189123Z","caller":"traceutil/trace.go:171","msg":"trace[947241839] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"447.554782ms","start":"2026-05-21T19:20:54.741513Z","end":"2026-05-21T19:20:55.189068Z","steps":["trace[947241839] 'read index received' (duration: 9.593484ms)","trace[947241839] 'applied index is now lower than readState.Index' (duration: 437.958498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:55.189191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:54.683503Z","time spent":"505.594322ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:55.189253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:54.684416Z","time spent":"504.757787ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:55.18938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.85466ms","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-05-21T19:20:55.18942Z","caller":"traceutil/trace.go:171","msg":"trace[1323828453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:358; }","duration":"447.947844ms","start":"2026-05-21T19:20:54.741461Z","end":"2026-05-21T19:20:55.189409Z","steps":["trace[1323828453] 'agreement among raft nodes before linearized reading' (duration: 447.787079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:55.189454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:54.741444Z","time spent":"448.002315ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:20:55.976922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.714784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:55.977031Z","caller":"traceutil/trace.go:171","msg":"trace[757105207] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"782.826608ms","start":"2026-05-21T19:20:55.194186Z","end":"2026-05-21T19:20:55.977013Z","steps":["trace[757105207] 'process raft request' (duration: 399.9546ms)","trace[757105207] 'compare' (duration: 382.549938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:55.977087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.194166Z","time spent":"782.89869ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:20:56.011478Z","caller":"traceutil/trace.go:171","msg":"trace[1764399257] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"816.72981ms","start":"2026-05-21T19:20:55.194727Z","end":"2026-05-21T19:20:56.011457Z","steps":["trace[1764399257] 'process raft request' (duration: 816.556505ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:56.011506Z","caller":"traceutil/trace.go:171","msg":"trace[224428677] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"817.254986ms","start":"2026-05-21T19:20:55.194222Z","end":"2026-05-21T19:20:56.011477Z","steps":["trace[224428677] 'process raft request' (duration: 816.986728ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:56.011695Z","caller":"traceutil/trace.go:171","msg":"trace[48479276] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"815.257637ms","start":"2026-05-21T19:20:55.196402Z","end":"2026-05-21T19:20:56.011659Z","steps":["trace[48479276] 'process raft request' (duration: 814.973069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.011606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.194707Z","time spent":"816.827233ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:56.011825Z","caller":"traceutil/trace.go:171","msg":"trace[2105055032] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"814.853895ms","start":"2026-05-21T19:20:55.196922Z","end":"2026-05-21T19:20:56.011776Z","steps":["trace[2105055032] 'process raft request' (duration: 814.498475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.011728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.194199Z","time spent":"817.463182ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:56.011889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.196356Z","time spent":"815.430832ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:56.011644Z","caller":"traceutil/trace.go:171","msg":"trace[866734322] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"816.799392ms","start":"2026-05-21T19:20:55.194823Z","end":"2026-05-21T19:20:56.011622Z","steps":["trace[866734322] 'process raft request' (duration: 816.514914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.012031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.194807Z","time spent":"817.168963ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:56.012005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.19691Z","time spent":"815.00725ms","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:56.012088Z","caller":"traceutil/trace.go:171","msg":"trace[2040820884] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:373; }","duration":"815.287738ms","start":"2026-05-21T19:20:55.196751Z","end":"2026-05-21T19:20:56.012039Z","steps":["trace[2040820884] 'read index received' (duration: 397.492248ms)","trace[2040820884] 'applied index is now lower than readState.Index' (duration: 417.79247ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:56.012168Z","caller":"traceutil/trace.go:171","msg":"trace[2138686234] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"812.365702ms","start":"2026-05-21T19:20:55.199785Z","end":"2026-05-21T19:20:56.012151Z","steps":["trace[2138686234] 'process raft request' (duration: 811.896509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.012263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.199776Z","time spent":"812.431444ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:56.012398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.692037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T19:20:56.012435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.639218ms","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-05-21T19:20:56.012489Z","caller":"traceutil/trace.go:171","msg":"trace[171608606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"815.758952ms","start":"2026-05-21T19:20:55.196716Z","end":"2026-05-21T19:20:56.012475Z","steps":["trace[171608606] 'agreement among raft nodes before linearized reading' (duration: 815.566746ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:56.012542Z","caller":"traceutil/trace.go:171","msg":"trace[794480778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:365; }","duration":"679.826531ms","start":"2026-05-21T19:20:55.332638Z","end":"2026-05-21T19:20:56.012465Z","steps":["trace[794480778] 'agreement among raft nodes before linearized reading' (duration: 679.703368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.012621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.332622Z","time spent":"679.987706ms","remote":"127.0.0.1:59124","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-05-21T19:20:56.012716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.172839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:20:56.012542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:55.196705Z","time spent":"815.826923ms","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-21T19:20:56.012815Z","caller":"traceutil/trace.go:171","msg":"trace[1029742791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"159.312493ms","start":"2026-05-21T19:20:55.853486Z","end":"2026-05-21T19:20:56.012799Z","steps":["trace[1029742791] 'agreement among raft nodes before linearized reading' (duration: 159.088947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.355176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.832269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:56.355365Z","caller":"traceutil/trace.go:171","msg":"trace[616432083] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"337.864983ms","start":"2026-05-21T19:20:56.017471Z","end":"2026-05-21T19:20:56.355336Z","steps":["trace[616432083] 'process raft request' (duration: 114.755776ms)","trace[616432083] 'compare' (duration: 222.293043ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:56.355443Z","caller":"traceutil/trace.go:171","msg":"trace[1488608095] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"336.668368ms","start":"2026-05-21T19:20:56.01875Z","end":"2026-05-21T19:20:56.355418Z","steps":["trace[1488608095] 'read index received' (duration: 113.49384ms)","trace[1488608095] 'applied index is now lower than readState.Index' (duration: 223.171938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:56.355476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.017439Z","time spent":"337.980716ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:56.355616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.855234ms","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-05-21T19:20:56.355798Z","caller":"traceutil/trace.go:171","msg":"trace[154994513] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"336.927786ms","start":"2026-05-21T19:20:56.018851Z","end":"2026-05-21T19:20:56.355779Z","steps":["trace[154994513] 'process raft request' (duration: 336.496163ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:56.355811Z","caller":"traceutil/trace.go:171","msg":"trace[446390196] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:366; }","duration":"337.08654ms","start":"2026-05-21T19:20:56.018707Z","end":"2026-05-21T19:20:56.355793Z","steps":["trace[446390196] 'agreement among raft nodes before linearized reading' (duration: 336.780921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.355882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.018827Z","time spent":"337.010789ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:56.355956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.018684Z","time spent":"337.217094ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6989,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-21T19:20:56.356003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.700161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-21T19:20:56.356055Z","caller":"traceutil/trace.go:171","msg":"trace[634496406] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:369; }","duration":"334.765242ms","start":"2026-05-21T19:20:56.021274Z","end":"2026-05-21T19:20:56.35604Z","steps":["trace[634496406] 'agreement among raft nodes before linearized reading' (duration: 334.569197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.356095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.021268Z","time spent":"334.816365ms","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-21T19:20:56.356095Z","caller":"traceutil/trace.go:171","msg":"trace[184124823] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"333.591848ms","start":"2026-05-21T19:20:56.022482Z","end":"2026-05-21T19:20:56.356074Z","steps":["trace[184124823] 'process raft request' (duration: 333.29929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.356201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.016104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-21T19:20:56.356183Z","caller":"traceutil/trace.go:171","msg":"trace[1651857712] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"335.413922ms","start":"2026-05-21T19:20:56.020737Z","end":"2026-05-21T19:20:56.356151Z","steps":["trace[1651857712] 'process raft request' (duration: 334.925687ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:56.35625Z","caller":"traceutil/trace.go:171","msg":"trace[529665779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:369; }","duration":"223.108387ms","start":"2026-05-21T19:20:56.133126Z","end":"2026-05-21T19:20:56.356234Z","steps":["trace[529665779] 'agreement among raft nodes before linearized reading' (duration: 223.023035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:56.35635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.020715Z","time spent":"335.538326ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:56.356841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.022475Z","time spent":"334.322849ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:57.343772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.278063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:57.344069Z","caller":"traceutil/trace.go:171","msg":"trace[1396905301] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"982.315535ms","start":"2026-05-21T19:20:56.361722Z","end":"2026-05-21T19:20:57.344038Z","steps":["trace[1396905301] 'process raft request' (duration: 982.13937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:57.344222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.361714Z","time spent":"982.429088ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:20:57.344217Z","caller":"traceutil/trace.go:171","msg":"trace[1891075118] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"980.663507ms","start":"2026-05-21T19:20:56.363507Z","end":"2026-05-21T19:20:57.34417Z","steps":["trace[1891075118] 'process raft request' (duration: 980.474872ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:57.344068Z","caller":"traceutil/trace.go:171","msg":"trace[705864008] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"982.766939ms","start":"2026-05-21T19:20:56.36125Z","end":"2026-05-21T19:20:57.344017Z","steps":["trace[705864008] 'process raft request' (duration: 770.182487ms)","trace[705864008] 'compare' (duration: 211.425458ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:57.344368Z","caller":"traceutil/trace.go:171","msg":"trace[1037423378] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:385; }","duration":"150.402031ms","start":"2026-05-21T19:20:57.193946Z","end":"2026-05-21T19:20:57.344348Z","steps":["trace[1037423378] 'read index received' (duration: 55.012µs)","trace[1037423378] 'applied index is now lower than readState.Index' (duration: 150.345519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:57.344373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.361232Z","time spent":"983.114848ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:57.34438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.363494Z","time spent":"980.795331ms","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:57.344313Z","caller":"traceutil/trace.go:171","msg":"trace[1597755633] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"979.39231ms","start":"2026-05-21T19:20:56.364806Z","end":"2026-05-21T19:20:57.344199Z","steps":["trace[1597755633] 'process raft request' (duration: 979.223555ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:57.344496Z","caller":"traceutil/trace.go:171","msg":"trace[1915661425] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"974.25684ms","start":"2026-05-21T19:20:56.37023Z","end":"2026-05-21T19:20:57.344487Z","steps":["trace[1915661425] 'process raft request' (duration: 973.862989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:57.344716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.364796Z","time spent":"979.722109ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:57.344461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.514614ms","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-05-21T19:20:57.344979Z","caller":"traceutil/trace.go:171","msg":"trace[107688816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"151.06938ms","start":"2026-05-21T19:20:57.19389Z","end":"2026-05-21T19:20:57.34496Z","steps":["trace[107688816] 'agreement among raft nodes before linearized reading' (duration: 150.522234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:57.345363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:56.370216Z","time spent":"974.313613ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:58.186361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.069024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:58.186642Z","caller":"traceutil/trace.go:171","msg":"trace[1866687756] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"836.602302ms","start":"2026-05-21T19:20:57.35001Z","end":"2026-05-21T19:20:58.186613Z","steps":["trace[1866687756] 'process raft request' (duration: 122.21775ms)","trace[1866687756] 'compare' (duration: 713.914828ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:58.18694Z","caller":"traceutil/trace.go:171","msg":"trace[2037797243] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"836.365025ms","start":"2026-05-21T19:20:57.350554Z","end":"2026-05-21T19:20:58.186919Z","steps":["trace[2037797243] 'process raft request' (duration: 835.918042ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.187087Z","caller":"traceutil/trace.go:171","msg":"trace[1531171405] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"836.057066ms","start":"2026-05-21T19:20:57.351011Z","end":"2026-05-21T19:20:58.187068Z","steps":["trace[1531171405] 'process raft request' (duration: 835.744337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:58.18717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.350999Z","time spent":"836.134628ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:20:58.187302Z","caller":"traceutil/trace.go:171","msg":"trace[1600216759] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:390; }","duration":"170.665098ms","start":"2026-05-21T19:20:58.016619Z","end":"2026-05-21T19:20:58.187285Z","steps":["trace[1600216759] 'read index received' (duration: 29.09649ms)","trace[1600216759] 'applied index is now lower than readState.Index' (duration: 141.567558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:58.187445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.805873ms","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-05-21T19:20:58.187495Z","caller":"traceutil/trace.go:171","msg":"trace[1934162282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:382; }","duration":"170.919506ms","start":"2026-05-21T19:20:58.016563Z","end":"2026-05-21T19:20:58.187483Z","steps":["trace[1934162282] 'agreement among raft nodes before linearized reading' (duration: 170.793532ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.187489Z","caller":"traceutil/trace.go:171","msg":"trace[691201225] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"835.431778ms","start":"2026-05-21T19:20:57.352046Z","end":"2026-05-21T19:20:58.187478Z","steps":["trace[691201225] 'process raft request' (duration: 834.900882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.187358Z","caller":"traceutil/trace.go:171","msg":"trace[569900984] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"834.235583ms","start":"2026-05-21T19:20:57.35309Z","end":"2026-05-21T19:20:58.187325Z","steps":["trace[569900984] 'process raft request' (duration: 833.934225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:58.187592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.35204Z","time spent":"835.504079ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:58.187731Z","caller":"traceutil/trace.go:171","msg":"trace[1106985580] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"567.664467ms","start":"2026-05-21T19:20:57.620049Z","end":"2026-05-21T19:20:58.187713Z","steps":["trace[1106985580] 'process raft request' (duration: 567.126082ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.187761Z","caller":"traceutil/trace.go:171","msg":"trace[112242105] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"832.035789ms","start":"2026-05-21T19:20:57.355709Z","end":"2026-05-21T19:20:58.187745Z","steps":["trace[112242105] 'process raft request' (duration: 831.36334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:58.187828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.620024Z","time spent":"567.75483ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:58.187591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.350529Z","time spent":"836.496909ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:58.187787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.349988Z","time spent":"836.857458ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:20:58.187868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.355703Z","time spent":"832.097472ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:58.188147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:57.353083Z","time spent":"834.554732ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:58.573205Z","caller":"traceutil/trace.go:171","msg":"trace[205757238] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:397; }","duration":"371.862398ms","start":"2026-05-21T19:20:58.201309Z","end":"2026-05-21T19:20:58.573172Z","steps":["trace[205757238] 'read index received' (duration: 69.77197ms)","trace[205757238] 'applied index is now lower than readState.Index' (duration: 302.089158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:58.573484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.128816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-21T19:20:58.573598Z","caller":"traceutil/trace.go:171","msg":"trace[1435978136] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:384; }","duration":"372.29263ms","start":"2026-05-21T19:20:58.201281Z","end":"2026-05-21T19:20:58.573574Z","steps":["trace[1435978136] 'agreement among raft nodes before linearized reading' (duration: 372.055104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:58.573656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.201272Z","time spent":"372.374363ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-21T19:20:58.57364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.963336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:20:58.573545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.41051ms","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-05-21T19:20:58.573782Z","caller":"traceutil/trace.go:171","msg":"trace[242838878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"212.175383ms","start":"2026-05-21T19:20:58.36158Z","end":"2026-05-21T19:20:58.573756Z","steps":["trace[242838878] 'agreement among raft nodes before linearized reading' (duration: 211.945816ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.573905Z","caller":"traceutil/trace.go:171","msg":"trace[831764210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"212.79345ms","start":"2026-05-21T19:20:58.361047Z","end":"2026-05-21T19:20:58.573841Z","steps":["trace[831764210] 'agreement among raft nodes before linearized reading' (duration: 212.406929ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:20:58.574452Z","caller":"traceutil/trace.go:171","msg":"trace[534538754] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"378.006183ms","start":"2026-05-21T19:20:58.195939Z","end":"2026-05-21T19:20:58.573945Z","steps":["trace[534538754] 'process raft request' (duration: 279.309057ms)","trace[534538754] 'compare' (duration: 97.760129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:58.574813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.195908Z","time spent":"378.614801ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:58.934901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.884604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:58.935026Z","caller":"traceutil/trace.go:171","msg":"trace[146850149] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"356.637674ms","start":"2026-05-21T19:20:58.578371Z","end":"2026-05-21T19:20:58.935009Z","steps":["trace[146850149] 'process raft request' (duration: 164.550164ms)","trace[146850149] 'compare' (duration: 191.758311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:58.935135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.578345Z","time spent":"356.717016ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:20:59.632018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.496309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:20:59.632295Z","caller":"traceutil/trace.go:171","msg":"trace[1244934941] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"1.053641639s","start":"2026-05-21T19:20:58.578575Z","end":"2026-05-21T19:20:59.632217Z","steps":["trace[1244934941] 'process raft request' (duration: 653.43516ms)","trace[1244934941] 'compare' (duration: 399.290483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:20:59.632412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.578565Z","time spent":"1.053779243s","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:59.634945Z","caller":"traceutil/trace.go:171","msg":"trace[590631641] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:399; }","duration":"964.519216ms","start":"2026-05-21T19:20:58.670334Z","end":"2026-05-21T19:20:59.634853Z","steps":["trace[590631641] 'read index received' (duration: 72.674132ms)","trace[590631641] 'applied index is now lower than readState.Index' (duration: 891.843164ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:20:59.635147Z","caller":"traceutil/trace.go:171","msg":"trace[1783936649] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.05405318s","start":"2026-05-21T19:20:58.58106Z","end":"2026-05-21T19:20:59.635113Z","steps":["trace[1783936649] 'process raft request' (duration: 1.051093606s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:59.635204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.838755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T19:20:59.635285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.581049Z","time spent":"1.054173504s","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:20:59.635276Z","caller":"traceutil/trace.go:171","msg":"trace[1642657568] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:388; }","duration":"964.959819ms","start":"2026-05-21T19:20:58.67028Z","end":"2026-05-21T19:20:59.63524Z","steps":["trace[1642657568] 'agreement among raft nodes before linearized reading' (duration: 964.748083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:59.635359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.670258Z","time spent":"965.081842ms","remote":"127.0.0.1:58898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"info","ts":"2026-05-21T19:20:59.635882Z","caller":"traceutil/trace.go:171","msg":"trace[1707360523] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"1.05406721s","start":"2026-05-21T19:20:58.581792Z","end":"2026-05-21T19:20:59.635859Z","steps":["trace[1707360523] 'process raft request' (duration: 1.052917728s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:20:59.636007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:58.581784Z","time spent":"1.054136313s","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:21:00.140418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186448605696347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:21:00.390006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.601207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:21:00.390309Z","caller":"traceutil/trace.go:171","msg":"trace[1983480270] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"887.793877ms","start":"2026-05-21T19:20:59.502454Z","end":"2026-05-21T19:21:00.390248Z","steps":["trace[1983480270] 'process raft request' (duration: 761.866741ms)","trace[1983480270] 'compare' (duration: 125.441292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:00.390555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:59.502429Z","time spent":"888.027124ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:21:00.390669Z","caller":"traceutil/trace.go:171","msg":"trace[1085129472] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"750.894788ms","start":"2026-05-21T19:20:59.639752Z","end":"2026-05-21T19:21:00.390646Z","steps":["trace[1085129472] 'read index received' (duration: 624.584642ms)","trace[1085129472] 'applied index is now lower than readState.Index' (duration: 126.308716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:00.390699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:59.636884Z","time spent":"753.81012ms","remote":"127.0.0.1:58898","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:21:00.390817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.054662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-21T19:21:00.390859Z","caller":"traceutil/trace.go:171","msg":"trace[362424534] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:389; }","duration":"751.121945ms","start":"2026-05-21T19:20:59.639723Z","end":"2026-05-21T19:21:00.390845Z","steps":["trace[362424534] 'agreement among raft nodes before linearized reading' (duration: 751.029072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:00.390913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:20:59.639709Z","time spent":"751.191946ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-21T19:21:00.452078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.355013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:21:00.452154Z","caller":"traceutil/trace.go:171","msg":"trace[1070369821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"258.438386ms","start":"2026-05-21T19:21:00.193697Z","end":"2026-05-21T19:21:00.452135Z","steps":["trace[1070369821] 'agreement among raft nodes before linearized reading' (duration: 258.292022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:00.452151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.854132ms","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-05-21T19:21:00.45221Z","caller":"traceutil/trace.go:171","msg":"trace[1487582875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"176.951064ms","start":"2026-05-21T19:21:00.275242Z","end":"2026-05-21T19:21:00.452193Z","steps":["trace[1487582875] 'agreement among raft nodes before linearized reading' (duration: 176.840191ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:00.694968Z","caller":"traceutil/trace.go:171","msg":"trace[1158561086] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"236.879272ms","start":"2026-05-21T19:21:00.458073Z","end":"2026-05-21T19:21:00.694952Z","steps":["trace[1158561086] 'process raft request' (duration: 236.83598ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:00.695034Z","caller":"traceutil/trace.go:171","msg":"trace[609414038] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"236.992344ms","start":"2026-05-21T19:21:00.458029Z","end":"2026-05-21T19:21:00.695021Z","steps":["trace[609414038] 'process raft request' (duration: 236.83388ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:00.694943Z","caller":"traceutil/trace.go:171","msg":"trace[632814382] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"303.27284ms","start":"2026-05-21T19:21:00.391634Z","end":"2026-05-21T19:21:00.694907Z","steps":["trace[632814382] 'process raft request' (duration: 253.100116ms)","trace[632814382] 'compare' (duration: 49.917557ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:21:00.694972Z","caller":"traceutil/trace.go:171","msg":"trace[839566651] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"295.333378ms","start":"2026-05-21T19:21:00.399601Z","end":"2026-05-21T19:21:00.694935Z","steps":["trace[839566651] 'process raft request' (duration: 295.191934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:00.695298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:00.391608Z","time spent":"303.584769ms","remote":"127.0.0.1:58898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:21:10.017707Z","caller":"traceutil/trace.go:171","msg":"trace[251155865] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"215.201991ms","start":"2026-05-21T19:21:09.802482Z","end":"2026-05-21T19:21:10.017684Z","steps":["trace[251155865] 'process raft request' (duration: 215.086808ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:12.993958Z","caller":"traceutil/trace.go:171","msg":"trace[1913256786] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"117.167916ms","start":"2026-05-21T19:21:12.876766Z","end":"2026-05-21T19:21:12.993934Z","steps":["trace[1913256786] 'read index received' (duration: 116.971381ms)","trace[1913256786] 'applied index is now lower than readState.Index' (duration: 195.725µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:12.994109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.30247ms","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-05-21T19:21:12.994217Z","caller":"traceutil/trace.go:171","msg":"trace[1899104787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"117.410463ms","start":"2026-05-21T19:21:12.876735Z","end":"2026-05-21T19:21:12.994145Z","steps":["trace[1899104787] 'agreement among raft nodes before linearized reading' (duration: 117.29438ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:12.994132Z","caller":"traceutil/trace.go:171","msg":"trace[254731008] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"132.624935ms","start":"2026-05-21T19:21:12.861454Z","end":"2026-05-21T19:21:12.994079Z","steps":["trace[254731008] 'process raft request' (duration: 132.334498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:19.50833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.051128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-21T19:21:19.508424Z","caller":"traceutil/trace.go:171","msg":"trace[1777666740] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:445; }","duration":"185.189722ms","start":"2026-05-21T19:21:19.323213Z","end":"2026-05-21T19:21:19.508403Z","steps":["trace[1777666740] 'range keys from in-memory index tree' (duration: 184.930936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:19.508546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.204095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T19:21:19.508615Z","caller":"traceutil/trace.go:171","msg":"trace[681893704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:445; }","duration":"312.309537ms","start":"2026-05-21T19:21:19.19628Z","end":"2026-05-21T19:21:19.508589Z","steps":["trace[681893704] 'range keys from in-memory index tree' (duration: 312.090362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:19.508681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:19.19625Z","time spent":"312.42161ms","remote":"127.0.0.1:59124","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-05-21T19:21:19.834472Z","caller":"traceutil/trace.go:171","msg":"trace[112581545] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"311.952618ms","start":"2026-05-21T19:21:19.522487Z","end":"2026-05-21T19:21:19.83444Z","steps":["trace[112581545] 'process raft request' (duration: 277.736277ms)","trace[112581545] 'compare' (duration: 34.117489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:19.834718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:19.522458Z","time spent":"312.164934ms","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:21:20.619584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186448605696500,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:21:20.649908Z","caller":"traceutil/trace.go:171","msg":"trace[674045289] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"530.884559ms","start":"2026-05-21T19:21:20.118998Z","end":"2026-05-21T19:21:20.649882Z","steps":["trace[674045289] 'read index received' (duration: 511.183225ms)","trace[674045289] 'applied index is now lower than readState.Index' (duration: 19.700084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:20.650137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.281353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:21:20.650181Z","caller":"traceutil/trace.go:171","msg":"trace[1637618166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"172.384596ms","start":"2026-05-21T19:21:20.477786Z","end":"2026-05-21T19:21:20.65017Z","steps":["trace[1637618166] 'agreement among raft nodes before linearized reading' (duration: 172.266163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:20.65034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.292469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:21:20.650476Z","caller":"traceutil/trace.go:171","msg":"trace[1602516270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"531.481833ms","start":"2026-05-21T19:21:20.118963Z","end":"2026-05-21T19:21:20.650444Z","steps":["trace[1602516270] 'agreement among raft nodes before linearized reading' (duration: 531.021822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:20.650538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.118947Z","time spent":"531.579586ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:21:20.650781Z","caller":"traceutil/trace.go:171","msg":"trace[1039694960] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"804.169339ms","start":"2026-05-21T19:21:19.846557Z","end":"2026-05-21T19:21:20.650726Z","steps":["trace[1039694960] 'process raft request' (duration: 783.613055ms)","trace[1039694960] 'compare' (duration: 19.506629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:20.651057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:19.846533Z","time spent":"804.350783ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:21:21.050384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.967292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:21:21.050712Z","caller":"traceutil/trace.go:171","msg":"trace[1990083449] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"375.404483ms","start":"2026-05-21T19:21:20.675277Z","end":"2026-05-21T19:21:21.050681Z","steps":["trace[1990083449] 'process raft request' (duration: 119.045531ms)","trace[1990083449] 'compare' (duration: 255.826599ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:21:21.050916Z","caller":"traceutil/trace.go:171","msg":"trace[863773621] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"303.000679ms","start":"2026-05-21T19:21:20.747881Z","end":"2026-05-21T19:21:21.050881Z","steps":["trace[863773621] 'process raft request' (duration: 302.629061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:21.050953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.67526Z","time spent":"375.518285ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:21:21.051053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.747847Z","time spent":"303.143543ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:21:21.051087Z","caller":"traceutil/trace.go:171","msg":"trace[1918942825] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"300.860149ms","start":"2026-05-21T19:21:20.750207Z","end":"2026-05-21T19:21:21.051067Z","steps":["trace[1918942825] 'process raft request' (duration: 300.749586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:21.051249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.75019Z","time spent":"300.985762ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:21:21.051462Z","caller":"traceutil/trace.go:171","msg":"trace[1117877316] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"302.349804ms","start":"2026-05-21T19:21:20.749098Z","end":"2026-05-21T19:21:21.051448Z","steps":["trace[1117877316] 'process raft request' (duration: 301.77557ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:21.051486Z","caller":"traceutil/trace.go:171","msg":"trace[349949] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"303.49437ms","start":"2026-05-21T19:21:20.74798Z","end":"2026-05-21T19:21:21.051475Z","steps":["trace[349949] 'process raft request' (duration: 302.801844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:21.05154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.74907Z","time spent":"302.424966ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:21:21.051545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:20.74797Z","time spent":"303.548382ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:21:28.909185Z","caller":"traceutil/trace.go:171","msg":"trace[380608010] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"133.928179ms","start":"2026-05-21T19:21:28.775213Z","end":"2026-05-21T19:21:28.909141Z","steps":["trace[380608010] 'process raft request' (duration: 104.349865ms)","trace[380608010] 'compare' (duration: 29.043012ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:21:29.461456Z","caller":"traceutil/trace.go:171","msg":"trace[129745910] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"262.377274ms","start":"2026-05-21T19:21:29.199055Z","end":"2026-05-21T19:21:29.461432Z","steps":["trace[129745910] 'read index received' (duration: 262.281872ms)","trace[129745910] 'applied index is now lower than readState.Index' (duration: 94.032µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:21:29.461529Z","caller":"traceutil/trace.go:171","msg":"trace[1844059636] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"314.294942ms","start":"2026-05-21T19:21:29.147201Z","end":"2026-05-21T19:21:29.461496Z","steps":["trace[1844059636] 'process raft request' (duration: 314.083868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:29.461593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.525567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:21:29.461686Z","caller":"traceutil/trace.go:171","msg":"trace[1891724828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"262.68255ms","start":"2026-05-21T19:21:29.198989Z","end":"2026-05-21T19:21:29.461672Z","steps":["trace[1891724828] 'agreement among raft nodes before linearized reading' (duration: 262.540437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:29.46172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:29.147156Z","time spent":"314.462216ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:21:31.589674Z","caller":"traceutil/trace.go:171","msg":"trace[151708976] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"115.339812ms","start":"2026-05-21T19:21:31.474308Z","end":"2026-05-21T19:21:31.589648Z","steps":["trace[151708976] 'read index received' (duration: 115.20765ms)","trace[151708976] 'applied index is now lower than readState.Index' (duration: 121.422µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:21:31.589729Z","caller":"traceutil/trace.go:171","msg":"trace[1924804597] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"119.430881ms","start":"2026-05-21T19:21:31.470274Z","end":"2026-05-21T19:21:31.589705Z","steps":["trace[1924804597] 'process raft request' (duration: 119.245367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:31.589815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.490565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:21:31.589845Z","caller":"traceutil/trace.go:171","msg":"trace[99436452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"115.579918ms","start":"2026-05-21T19:21:31.474256Z","end":"2026-05-21T19:21:31.589836Z","steps":["trace[99436452] 'agreement among raft nodes before linearized reading' (duration: 115.505116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:31.590032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.071868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:21:31.59009Z","caller":"traceutil/trace.go:171","msg":"trace[1094358390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"110.15172ms","start":"2026-05-21T19:21:31.479921Z","end":"2026-05-21T19:21:31.590073Z","steps":["trace[1094358390] 'agreement among raft nodes before linearized reading' (duration: 109.903564ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787649Z","caller":"traceutil/trace.go:171","msg":"trace[843654154] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"150.677754ms","start":"2026-05-21T19:21:34.636938Z","end":"2026-05-21T19:21:34.787615Z","steps":["trace[843654154] 'process raft request' (duration: 150.586652ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787686Z","caller":"traceutil/trace.go:171","msg":"trace[191413352] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"145.663758ms","start":"2026-05-21T19:21:34.642002Z","end":"2026-05-21T19:21:34.787666Z","steps":["trace[191413352] 'process raft request' (duration: 145.591867ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787617Z","caller":"traceutil/trace.go:171","msg":"trace[1054233759] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"154.472555ms","start":"2026-05-21T19:21:34.633118Z","end":"2026-05-21T19:21:34.78759Z","steps":["trace[1054233759] 'process raft request' (duration: 154.209679ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787726Z","caller":"traceutil/trace.go:171","msg":"trace[677035124] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"145.953584ms","start":"2026-05-21T19:21:34.641712Z","end":"2026-05-21T19:21:34.787666Z","steps":["trace[677035124] 'process raft request' (duration: 145.848232ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.7878Z","caller":"traceutil/trace.go:171","msg":"trace[1068694193] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"145.177649ms","start":"2026-05-21T19:21:34.642594Z","end":"2026-05-21T19:21:34.787772Z","steps":["trace[1068694193] 'process raft request' (duration: 145.035206ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787874Z","caller":"traceutil/trace.go:171","msg":"trace[1794194601] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"144.81379ms","start":"2026-05-21T19:21:34.643048Z","end":"2026-05-21T19:21:34.787861Z","steps":["trace[1794194601] 'process raft request' (duration: 144.615306ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.787986Z","caller":"traceutil/trace.go:171","msg":"trace[145383285] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"144.470214ms","start":"2026-05-21T19:21:34.643507Z","end":"2026-05-21T19:21:34.787977Z","steps":["trace[145383285] 'process raft request' (duration: 144.218889ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.78811Z","caller":"traceutil/trace.go:171","msg":"trace[654652166] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"133.490232ms","start":"2026-05-21T19:21:34.654613Z","end":"2026-05-21T19:21:34.788103Z","steps":["trace[654652166] 'process raft request' (duration: 133.371259ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.788294Z","caller":"traceutil/trace.go:171","msg":"trace[1167332722] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"133.539692ms","start":"2026-05-21T19:21:34.654739Z","end":"2026-05-21T19:21:34.788279Z","steps":["trace[1167332722] 'process raft request' (duration: 133.297357ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.78828Z","caller":"traceutil/trace.go:171","msg":"trace[1093820260] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"107.183955ms","start":"2026-05-21T19:21:34.681077Z","end":"2026-05-21T19:21:34.788261Z","steps":["trace[1093820260] 'process raft request' (duration: 107.137244ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.797101Z","caller":"traceutil/trace.go:171","msg":"trace[921572345] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"102.080488ms","start":"2026-05-21T19:21:34.695007Z","end":"2026-05-21T19:21:34.797087Z","steps":["trace[921572345] 'process raft request' (duration: 101.71793ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:34.797261Z","caller":"traceutil/trace.go:171","msg":"trace[1091468453] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"100.439043ms","start":"2026-05-21T19:21:34.6968Z","end":"2026-05-21T19:21:34.797239Z","steps":["trace[1091468453] 'process raft request' (duration: 100.161477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:36.230284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.272698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:21:36.231101Z","caller":"traceutil/trace.go:171","msg":"trace[728166377] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"419.513699ms","start":"2026-05-21T19:21:35.811564Z","end":"2026-05-21T19:21:36.231077Z","steps":["trace[728166377] 'process raft request' (duration: 419.177882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:36.231103Z","caller":"traceutil/trace.go:171","msg":"trace[2044068236] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"586.767719ms","start":"2026-05-21T19:21:35.644294Z","end":"2026-05-21T19:21:36.231061Z","steps":["trace[2044068236] 'process raft request' (duration: 273.634543ms)","trace[2044068236] 'compare' (duration: 312.137695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:36.231219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:35.811534Z","time spent":"419.624211ms","remote":"127.0.0.1:58912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:21:36.231255Z","caller":"traceutil/trace.go:171","msg":"trace[349913291] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"527.290721ms","start":"2026-05-21T19:21:35.703911Z","end":"2026-05-21T19:21:36.231202Z","steps":["trace[349913291] 'process raft request' (duration: 526.517985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:36.231327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:35.644284Z","time spent":"586.913132ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:21:36.231395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:35.703876Z","time spent":"527.452253ms","remote":"127.0.0.1:58912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:21:43.168579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.600022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:21:43.168637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.4043ms","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-05-21T19:21:43.168719Z","caller":"traceutil/trace.go:171","msg":"trace[1162789992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"125.521682ms","start":"2026-05-21T19:21:43.043181Z","end":"2026-05-21T19:21:43.168702Z","steps":["trace[1162789992] 'range keys from in-memory index tree' (duration: 125.229727ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:43.168717Z","caller":"traceutil/trace.go:171","msg":"trace[1057605977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"687.794946ms","start":"2026-05-21T19:21:42.480899Z","end":"2026-05-21T19:21:43.168694Z","steps":["trace[1057605977] 'range keys from in-memory index tree' (duration: 687.384667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:43.168781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:42.480874Z","time spent":"687.889247ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:21:43.168947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.997759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ngdw5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T19:21:43.169043Z","caller":"traceutil/trace.go:171","msg":"trace[726433885] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ngdw5; range_end:; response_count:1; response_revision:661; }","duration":"782.510999ms","start":"2026-05-21T19:21:42.386505Z","end":"2026-05-21T19:21:43.169016Z","steps":["trace[726433885] 'range keys from in-memory index tree' (duration: 781.820345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:43.169126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:42.386477Z","time spent":"782.633072ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-ngdw5\" "} {"level":"info","ts":"2026-05-21T19:21:43.471751Z","caller":"traceutil/trace.go:171","msg":"trace[2108676623] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"285.550089ms","start":"2026-05-21T19:21:43.186167Z","end":"2026-05-21T19:21:43.471718Z","steps":["trace[2108676623] 'process raft request' (duration: 285.332225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:44.072862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.303558ms","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-05-21T19:21:44.073014Z","caller":"traceutil/trace.go:171","msg":"trace[1923896321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"400.502941ms","start":"2026-05-21T19:21:43.672484Z","end":"2026-05-21T19:21:44.072986Z","steps":["trace[1923896321] 'range keys from in-memory index tree' (duration: 400.044432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:44.073075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:43.672474Z","time spent":"400.590433ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:21:44.073448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.863389ms","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-05-21T19:21:44.073575Z","caller":"traceutil/trace.go:171","msg":"trace[1670261313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"401.377539ms","start":"2026-05-21T19:21:43.672162Z","end":"2026-05-21T19:21:44.07354Z","steps":["trace[1670261313] 'range keys from in-memory index tree' (duration: 400.580113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:44.073642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:43.672142Z","time spent":"401.482201ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:21:46.068125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587804392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:21:46.068265Z","caller":"traceutil/trace.go:171","msg":"trace[1440815830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.588003546s","start":"2026-05-21T19:21:44.480215Z","end":"2026-05-21T19:21:46.068219Z","steps":["trace[1440815830] 'range keys from in-memory index tree' (duration: 1.587621439s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:46.068406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:44.480187Z","time spent":"1.588131558s","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:21:46.068433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800035008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-21T19:21:46.06845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.354205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:21:46.068548Z","caller":"traceutil/trace.go:171","msg":"trace[546100650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"1.800227431s","start":"2026-05-21T19:21:44.268295Z","end":"2026-05-21T19:21:46.068522Z","steps":["trace[546100650] 'range keys from in-memory index tree' (duration: 1.799829824s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:46.068562Z","caller":"traceutil/trace.go:171","msg":"trace[998020830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"797.55246ms","start":"2026-05-21T19:21:45.270986Z","end":"2026-05-21T19:21:46.068539Z","steps":["trace[998020830] 'range keys from in-memory index tree' (duration: 797.169592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:46.068596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:44.268264Z","time spent":"1.800323864s","remote":"127.0.0.1:59124","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-05-21T19:21:46.068604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:45.270964Z","time spent":"797.630931ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:21:48.126931Z","caller":"traceutil/trace.go:171","msg":"trace[1866716050] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"292.802718ms","start":"2026-05-21T19:21:47.834103Z","end":"2026-05-21T19:21:48.126905Z","steps":["trace[1866716050] 'read index received' (duration: 292.554543ms)","trace[1866716050] 'applied index is now lower than readState.Index' (duration: 246.605µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:21:48.12726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.120835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:21:48.12734Z","caller":"traceutil/trace.go:171","msg":"trace[1202042693] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:689; }","duration":"293.298108ms","start":"2026-05-21T19:21:47.834024Z","end":"2026-05-21T19:21:48.127323Z","steps":["trace[1202042693] 'agreement among raft nodes before linearized reading' (duration: 293.037963ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:21:48.127012Z","caller":"traceutil/trace.go:171","msg":"trace[2081536724] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"482.962216ms","start":"2026-05-21T19:21:47.644007Z","end":"2026-05-21T19:21:48.126969Z","steps":["trace[2081536724] 'process raft request' (duration: 482.704351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:21:48.127494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:21:47.643969Z","time spent":"483.451915ms","remote":"127.0.0.1:58912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:21:48.132942Z","caller":"traceutil/trace.go:171","msg":"trace[688644444] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"291.716186ms","start":"2026-05-21T19:21:47.841201Z","end":"2026-05-21T19:21:48.132918Z","steps":["trace[688644444] 'process raft request' (duration: 291.3866ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:00.325558Z","caller":"traceutil/trace.go:171","msg":"trace[2098942206] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:842; }","duration":"122.544651ms","start":"2026-05-21T19:22:00.20299Z","end":"2026-05-21T19:22:00.325535Z","steps":["trace[2098942206] 'read index received' (duration: 30.937993ms)","trace[2098942206] 'applied index is now lower than readState.Index' (duration: 91.605188ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:22:00.325618Z","caller":"traceutil/trace.go:171","msg":"trace[582588446] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"124.566817ms","start":"2026-05-21T19:22:00.201007Z","end":"2026-05-21T19:22:00.325574Z","steps":["trace[582588446] 'process raft request' (duration: 124.366204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:00.325815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.804436ms","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-05-21T19:22:00.325865Z","caller":"traceutil/trace.go:171","msg":"trace[1207962835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"122.899037ms","start":"2026-05-21T19:22:00.202955Z","end":"2026-05-21T19:22:00.325854Z","steps":["trace[1207962835] 'agreement among raft nodes before linearized reading' (duration: 122.731934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:00.326035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.255604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:22:00.326069Z","caller":"traceutil/trace.go:171","msg":"trace[281829292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"118.333946ms","start":"2026-05-21T19:22:00.207728Z","end":"2026-05-21T19:22:00.326062Z","steps":["trace[281829292] 'agreement among raft nodes before linearized reading' (duration: 118.145853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:02.105894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186448605697288,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:22:02.474445Z","caller":"traceutil/trace.go:171","msg":"trace[225843310] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"972.773849ms","start":"2026-05-21T19:22:01.501648Z","end":"2026-05-21T19:22:02.474422Z","steps":["trace[225843310] 'process raft request' (duration: 972.597626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:02.474787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:01.501633Z","time spent":"973.024333ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:22:02.47983Z","caller":"traceutil/trace.go:171","msg":"trace[374079456] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:854; }","duration":"874.785846ms","start":"2026-05-21T19:22:01.605021Z","end":"2026-05-21T19:22:02.479807Z","steps":["trace[374079456] 'read index received' (duration: 869.272169ms)","trace[374079456] 'applied index is now lower than readState.Index' (duration: 5.512537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:02.480181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.150262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:22:02.480244Z","caller":"traceutil/trace.go:171","msg":"trace[340223702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"875.260704ms","start":"2026-05-21T19:22:01.60497Z","end":"2026-05-21T19:22:02.480231Z","steps":["trace[340223702] 'agreement among raft nodes before linearized reading' (duration: 875.052091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:02.4803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:01.604956Z","time spent":"875.320095ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:22:02.480234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.398716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T19:22:02.480357Z","caller":"traceutil/trace.go:171","msg":"trace[705698120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"242.561048ms","start":"2026-05-21T19:22:02.237784Z","end":"2026-05-21T19:22:02.480345Z","steps":["trace[705698120] 'agreement among raft nodes before linearized reading' (duration: 242.372705ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:02.480438Z","caller":"traceutil/trace.go:171","msg":"trace[240082112] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"638.521755ms","start":"2026-05-21T19:22:01.841906Z","end":"2026-05-21T19:22:02.480428Z","steps":["trace[240082112] 'process raft request' (duration: 637.750971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:02.480526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:01.841883Z","time spent":"638.585406ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:22:02.480542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.516172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:22:02.480574Z","caller":"traceutil/trace.go:171","msg":"trace[443463084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"141.573533ms","start":"2026-05-21T19:22:02.338992Z","end":"2026-05-21T19:22:02.480566Z","steps":["trace[443463084] 'agreement among raft nodes before linearized reading' (duration: 141.466411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:02.480726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.800468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:22:02.480748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.184303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:22:02.480758Z","caller":"traceutil/trace.go:171","msg":"trace[153765037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"145.852089ms","start":"2026-05-21T19:22:02.334899Z","end":"2026-05-21T19:22:02.480751Z","steps":["trace[153765037] 'agreement among raft nodes before linearized reading' (duration: 145.739427ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:02.480777Z","caller":"traceutil/trace.go:171","msg":"trace[267714038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"151.213294ms","start":"2026-05-21T19:22:02.329555Z","end":"2026-05-21T19:22:02.480768Z","steps":["trace[267714038] 'agreement among raft nodes before linearized reading' (duration: 151.129792ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:16.869551Z","caller":"traceutil/trace.go:171","msg":"trace[291585654] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"226.383404ms","start":"2026-05-21T19:22:16.643157Z","end":"2026-05-21T19:22:16.869541Z","steps":["trace[291585654] 'process raft request' (duration: 223.772311ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:16.869487Z","caller":"traceutil/trace.go:171","msg":"trace[713178316] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"225.096262ms","start":"2026-05-21T19:22:16.644346Z","end":"2026-05-21T19:22:16.869442Z","steps":["trace[713178316] 'read index received' (duration: 222.602221ms)","trace[713178316] 'applied index is now lower than readState.Index' (duration: 2.493021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:16.869778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.421258ms","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-05-21T19:22:16.869801Z","caller":"traceutil/trace.go:171","msg":"trace[804876301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"225.468309ms","start":"2026-05-21T19:22:16.644328Z","end":"2026-05-21T19:22:16.869796Z","steps":["trace[804876301] 'agreement among raft nodes before linearized reading' (duration: 225.380117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:16.869888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.425988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:22:16.869953Z","caller":"traceutil/trace.go:171","msg":"trace[597625107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"225.503129ms","start":"2026-05-21T19:22:16.644437Z","end":"2026-05-21T19:22:16.86994Z","steps":["trace[597625107] 'agreement among raft nodes before linearized reading' (duration: 225.337006ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:36.927552Z","caller":"traceutil/trace.go:171","msg":"trace[343155289] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"153.959674ms","start":"2026-05-21T19:22:36.773571Z","end":"2026-05-21T19:22:36.92753Z","steps":["trace[343155289] 'process raft request' (duration: 153.855292ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:39.134741Z","caller":"traceutil/trace.go:171","msg":"trace[1119066302] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"116.147184ms","start":"2026-05-21T19:22:39.018566Z","end":"2026-05-21T19:22:39.134713Z","steps":["trace[1119066302] 'read index received' (duration: 113.984352ms)","trace[1119066302] 'applied index is now lower than readState.Index' (duration: 2.161812ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:22:39.134951Z","caller":"traceutil/trace.go:171","msg":"trace[399431279] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"142.813503ms","start":"2026-05-21T19:22:38.992054Z","end":"2026-05-21T19:22:39.134867Z","steps":["trace[399431279] 'process raft request' (duration: 140.518229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:39.1355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.923426ms","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-05-21T19:22:39.135545Z","caller":"traceutil/trace.go:171","msg":"trace[345116460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"117.015067ms","start":"2026-05-21T19:22:39.018519Z","end":"2026-05-21T19:22:39.135534Z","steps":["trace[345116460] 'agreement among raft nodes before linearized reading' (duration: 116.870325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:40.787867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.29324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:22:40.787973Z","caller":"traceutil/trace.go:171","msg":"trace[1215610219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"307.478584ms","start":"2026-05-21T19:22:40.480469Z","end":"2026-05-21T19:22:40.787947Z","steps":["trace[1215610219] 'range keys from in-memory index tree' (duration: 307.160309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:40.788022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:40.480446Z","time spent":"307.562415ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:22:41.038855Z","caller":"traceutil/trace.go:171","msg":"trace[986843048] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"246.554066ms","start":"2026-05-21T19:22:40.792282Z","end":"2026-05-21T19:22:41.038836Z","steps":["trace[986843048] 'process raft request' (duration: 246.424484ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:43.313288Z","caller":"traceutil/trace.go:171","msg":"trace[1374027626] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"257.346757ms","start":"2026-05-21T19:22:43.055906Z","end":"2026-05-21T19:22:43.313253Z","steps":["trace[1374027626] 'read index received' (duration: 257.137424ms)","trace[1374027626] 'applied index is now lower than readState.Index' (duration: 207.683µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:22:43.313476Z","caller":"traceutil/trace.go:171","msg":"trace[78561015] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"265.00822ms","start":"2026-05-21T19:22:43.04844Z","end":"2026-05-21T19:22:43.313448Z","steps":["trace[78561015] 'process raft request' (duration: 264.544373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:43.313795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.688828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:22:43.314034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.651153ms","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-05-21T19:22:43.314093Z","caller":"traceutil/trace.go:171","msg":"trace[1883651014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"111.767775ms","start":"2026-05-21T19:22:43.20231Z","end":"2026-05-21T19:22:43.314078Z","steps":["trace[1883651014] 'agreement among raft nodes before linearized reading' (duration: 111.570422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:43.314106Z","caller":"traceutil/trace.go:171","msg":"trace[1099659408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"146.835851ms","start":"2026-05-21T19:22:43.16703Z","end":"2026-05-21T19:22:43.313866Z","steps":["trace[1099659408] 'agreement among raft nodes before linearized reading' (duration: 146.579617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:43.314178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.237451ms","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-05-21T19:22:43.314306Z","caller":"traceutil/trace.go:171","msg":"trace[281398079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"258.429963ms","start":"2026-05-21T19:22:43.05585Z","end":"2026-05-21T19:22:43.31428Z","steps":["trace[281398079] 'agreement among raft nodes before linearized reading' (duration: 257.983926ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:44.158104Z","caller":"traceutil/trace.go:171","msg":"trace[2059348274] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"342.521752ms","start":"2026-05-21T19:22:43.815561Z","end":"2026-05-21T19:22:44.158083Z","steps":["trace[2059348274] 'process raft request' (duration: 342.356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:44.158266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:43.815536Z","time spent":"342.636475ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:22:44.351305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.791543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-21T19:22:44.351406Z","caller":"traceutil/trace.go:171","msg":"trace[1185016043] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1139; }","duration":"365.060804ms","start":"2026-05-21T19:22:43.986328Z","end":"2026-05-21T19:22:44.351388Z","steps":["trace[1185016043] 'read index received' (duration: 171.682578ms)","trace[1185016043] 'applied index is now lower than readState.Index' (duration: 193.376686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:44.351525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.173577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:22:44.351571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.399945ms","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-05-21T19:22:44.351602Z","caller":"traceutil/trace.go:171","msg":"trace[807183851] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1095; }","duration":"365.263648ms","start":"2026-05-21T19:22:43.986319Z","end":"2026-05-21T19:22:44.351582Z","steps":["trace[807183851] 'agreement among raft nodes before linearized reading' (duration: 365.129906ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:44.351629Z","caller":"traceutil/trace.go:171","msg":"trace[388579275] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1095; }","duration":"182.491186ms","start":"2026-05-21T19:22:44.169123Z","end":"2026-05-21T19:22:44.351615Z","steps":["trace[388579275] 'agreement among raft nodes before linearized reading' (duration: 182.375825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:44.351643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:43.986281Z","time spent":"365.352529ms","remote":"127.0.0.1:59218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:22:46.299935Z","caller":"traceutil/trace.go:171","msg":"trace[492049053] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"137.586924ms","start":"2026-05-21T19:22:46.162321Z","end":"2026-05-21T19:22:46.299908Z","steps":["trace[492049053] 'process raft request' (duration: 137.163628ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:48.724778Z","caller":"traceutil/trace.go:171","msg":"trace[357016776] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"244.717961ms","start":"2026-05-21T19:22:48.480036Z","end":"2026-05-21T19:22:48.724754Z","steps":["trace[357016776] 'read index received' (duration: 244.447867ms)","trace[357016776] 'applied index is now lower than readState.Index' (duration: 269.034µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:48.724928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.881353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:22:48.72501Z","caller":"traceutil/trace.go:171","msg":"trace[496043241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"245.015315ms","start":"2026-05-21T19:22:48.479977Z","end":"2026-05-21T19:22:48.724992Z","steps":["trace[496043241] 'agreement among raft nodes before linearized reading' (duration: 244.887053ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:48.725376Z","caller":"traceutil/trace.go:171","msg":"trace[689745710] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"419.703262ms","start":"2026-05-21T19:22:48.305124Z","end":"2026-05-21T19:22:48.724827Z","steps":["trace[689745710] 'process raft request' (duration: 419.446718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:22:48.72548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:48.305083Z","time spent":"420.33472ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:22:49.690499Z","caller":"traceutil/trace.go:171","msg":"trace[1196697475] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"102.332856ms","start":"2026-05-21T19:22:49.588147Z","end":"2026-05-21T19:22:49.69048Z","steps":["trace[1196697475] 'process raft request' (duration: 12.792964ms)","trace[1196697475] 'compare' (duration: 89.42984ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:22:49.691291Z","caller":"traceutil/trace.go:171","msg":"trace[1478734701] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"103.057206ms","start":"2026-05-21T19:22:49.588216Z","end":"2026-05-21T19:22:49.691273Z","steps":["trace[1478734701] 'process raft request' (duration: 102.966935ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:51.96187Z","caller":"traceutil/trace.go:171","msg":"trace[1901178474] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"266.540839ms","start":"2026-05-21T19:22:51.695307Z","end":"2026-05-21T19:22:51.961847Z","steps":["trace[1901178474] 'read index received' (duration: 244.686936ms)","trace[1901178474] 'applied index is now lower than readState.Index' (duration: 21.853033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:51.962169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.846393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:22:51.962192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.985191ms","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-05-21T19:22:51.962215Z","caller":"traceutil/trace.go:171","msg":"trace[985212105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"266.945575ms","start":"2026-05-21T19:22:51.695256Z","end":"2026-05-21T19:22:51.962202Z","steps":["trace[985212105] 'agreement among raft nodes before linearized reading' (duration: 266.780743ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:51.962232Z","caller":"traceutil/trace.go:171","msg":"trace[1630723284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"266.031602ms","start":"2026-05-21T19:22:51.69619Z","end":"2026-05-21T19:22:51.962221Z","steps":["trace[1630723284] 'agreement among raft nodes before linearized reading' (duration: 265.92058ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:22:51.962335Z","caller":"traceutil/trace.go:171","msg":"trace[1673290169] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"334.444022ms","start":"2026-05-21T19:22:51.627879Z","end":"2026-05-21T19:22:51.962323Z","steps":["trace[1673290169] 'process raft request' (duration: 312.113922ms)","trace[1673290169] 'compare' (duration: 21.738032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:22:51.963795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:22:51.627858Z","time spent":"334.498783ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:22:52.158028Z","caller":"traceutil/trace.go:171","msg":"trace[14842340] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"190.117426ms","start":"2026-05-21T19:22:51.96788Z","end":"2026-05-21T19:22:52.157997Z","steps":["trace[14842340] 'process raft request' (duration: 154.553428ms)","trace[14842340] 'compare' (duration: 34.637034ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:06.473607Z","caller":"traceutil/trace.go:171","msg":"trace[1772488084] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"279.213891ms","start":"2026-05-21T19:23:06.194369Z","end":"2026-05-21T19:23:06.473583Z","steps":["trace[1772488084] 'read index received' (duration: 279.12264ms)","trace[1772488084] 'applied index is now lower than readState.Index' (duration: 90.271µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:06.473676Z","caller":"traceutil/trace.go:171","msg":"trace[273877940] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"391.233669ms","start":"2026-05-21T19:23:06.082409Z","end":"2026-05-21T19:23:06.473643Z","steps":["trace[273877940] 'process raft request' (duration: 391.028736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:06.473822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.438874ms","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":"warn","ts":"2026-05-21T19:23:06.473826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:23:06.082384Z","time spent":"391.3603ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:23:06.473857Z","caller":"traceutil/trace.go:171","msg":"trace[2109300223] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1164; }","duration":"279.529585ms","start":"2026-05-21T19:23:06.194317Z","end":"2026-05-21T19:23:06.473847Z","steps":["trace[2109300223] 'agreement among raft nodes before linearized reading' (duration: 279.388853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:06.473966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.024319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:23:06.474019Z","caller":"traceutil/trace.go:171","msg":"trace[201996660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1164; }","duration":"228.1171ms","start":"2026-05-21T19:23:06.245888Z","end":"2026-05-21T19:23:06.474005Z","steps":["trace[201996660] 'agreement among raft nodes before linearized reading' (duration: 227.946047ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:07.655947Z","caller":"traceutil/trace.go:171","msg":"trace[1692806014] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"120.859384ms","start":"2026-05-21T19:23:07.535049Z","end":"2026-05-21T19:23:07.655909Z","steps":["trace[1692806014] 'process raft request' (duration: 120.506139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:08.802987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.751017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T19:23:08.803181Z","caller":"traceutil/trace.go:171","msg":"trace[2123498373] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:1172; }","duration":"126.058451ms","start":"2026-05-21T19:23:08.677094Z","end":"2026-05-21T19:23:08.803153Z","steps":["trace[2123498373] 'range keys from in-memory index tree' (duration: 125.420442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:09.940962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.056992ms","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-05-21T19:23:09.94104Z","caller":"traceutil/trace.go:171","msg":"trace[799594393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"100.187893ms","start":"2026-05-21T19:23:09.840833Z","end":"2026-05-21T19:23:09.941021Z","steps":["trace[799594393] 'range keys from in-memory index tree' (duration: 99.888059ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:10.398587Z","caller":"traceutil/trace.go:171","msg":"trace[1832623069] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"262.11527ms","start":"2026-05-21T19:23:10.136456Z","end":"2026-05-21T19:23:10.398571Z","steps":["trace[1832623069] 'read index received' (duration: 261.966768ms)","trace[1832623069] 'applied index is now lower than readState.Index' (duration: 147.982µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:10.398665Z","caller":"traceutil/trace.go:171","msg":"trace[625882670] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"453.761766ms","start":"2026-05-21T19:23:09.94486Z","end":"2026-05-21T19:23:10.398622Z","steps":["trace[625882670] 'process raft request' (duration: 453.608854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:10.398815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.308282ms","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-05-21T19:23:10.398851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:23:09.944838Z","time spent":"453.911767ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:23:10.39892Z","caller":"traceutil/trace.go:171","msg":"trace[1873096836] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1176; }","duration":"262.474635ms","start":"2026-05-21T19:23:10.136408Z","end":"2026-05-21T19:23:10.398883Z","steps":["trace[1873096836] 'agreement among raft nodes before linearized reading' (duration: 262.249682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:14.201731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.757451ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:23:14.201864Z","caller":"traceutil/trace.go:171","msg":"trace[1851282698] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1188; }","duration":"142.910223ms","start":"2026-05-21T19:23:14.058933Z","end":"2026-05-21T19:23:14.201843Z","steps":["trace[1851282698] 'range keys from in-memory index tree' (duration: 142.72396ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:14.855155Z","caller":"traceutil/trace.go:171","msg":"trace[488155723] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"137.195746ms","start":"2026-05-21T19:23:14.717934Z","end":"2026-05-21T19:23:14.85513Z","steps":["trace[488155723] 'process raft request' (duration: 128.519569ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:14.928127Z","caller":"traceutil/trace.go:171","msg":"trace[1898849598] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"208.809719ms","start":"2026-05-21T19:23:14.719293Z","end":"2026-05-21T19:23:14.928103Z","steps":["trace[1898849598] 'process raft request' (duration: 208.676578ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:18.935648Z","caller":"traceutil/trace.go:171","msg":"trace[583077164] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"110.712636ms","start":"2026-05-21T19:23:18.824898Z","end":"2026-05-21T19:23:18.935611Z","steps":["trace[583077164] 'process raft request' (duration: 80.21761ms)","trace[583077164] 'compare' (duration: 30.335754ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:19.045407Z","caller":"traceutil/trace.go:171","msg":"trace[172310917] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"105.946552ms","start":"2026-05-21T19:23:18.939443Z","end":"2026-05-21T19:23:19.045389Z","steps":["trace[172310917] 'read index received' (duration: 103.821723ms)","trace[172310917] 'applied index is now lower than readState.Index' (duration: 2.123739ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:19.045469Z","caller":"traceutil/trace.go:171","msg":"trace[206465353] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"106.535959ms","start":"2026-05-21T19:23:18.938906Z","end":"2026-05-21T19:23:19.045442Z","steps":["trace[206465353] 'process raft request' (duration: 104.506662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:19.045549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.096633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-21T19:23:19.045584Z","caller":"traceutil/trace.go:171","msg":"trace[1374130495] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1205; }","duration":"106.170194ms","start":"2026-05-21T19:23:18.939404Z","end":"2026-05-21T19:23:19.045574Z","steps":["trace[1374130495] 'agreement among raft nodes before linearized reading' (duration: 106.093973ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:25.99642Z","caller":"traceutil/trace.go:171","msg":"trace[4893753] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"170.279386ms","start":"2026-05-21T19:23:25.82612Z","end":"2026-05-21T19:23:25.996399Z","steps":["trace[4893753] 'process raft request' (duration: 170.110734ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:39.410144Z","caller":"traceutil/trace.go:171","msg":"trace[1765859059] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"164.415345ms","start":"2026-05-21T19:23:39.245689Z","end":"2026-05-21T19:23:39.410105Z","steps":["trace[1765859059] 'process raft request' (duration: 156.569114ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:39.410328Z","caller":"traceutil/trace.go:171","msg":"trace[962292323] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"136.914842ms","start":"2026-05-21T19:23:39.273391Z","end":"2026-05-21T19:23:39.410306Z","steps":["trace[962292323] 'process raft request' (duration: 136.548208ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:41.42983Z","caller":"traceutil/trace.go:171","msg":"trace[267530421] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"112.384014ms","start":"2026-05-21T19:23:41.317416Z","end":"2026-05-21T19:23:41.4298Z","steps":["trace[267530421] 'process raft request' (duration: 112.193981ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:48.26001Z","caller":"traceutil/trace.go:171","msg":"trace[1557622108] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"139.011575ms","start":"2026-05-21T19:23:48.120977Z","end":"2026-05-21T19:23:48.259989Z","steps":["trace[1557622108] 'process raft request' (duration: 138.870263ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:55.740775Z","caller":"traceutil/trace.go:171","msg":"trace[555491473] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"196.182662ms","start":"2026-05-21T19:23:55.544567Z","end":"2026-05-21T19:23:55.74075Z","steps":["trace[555491473] 'process raft request' (duration: 195.98279ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:23:55.755122Z","caller":"traceutil/trace.go:171","msg":"trace[2110072560] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1421; }","duration":"178.34757ms","start":"2026-05-21T19:23:55.576761Z","end":"2026-05-21T19:23:55.755108Z","steps":["trace[2110072560] 'read index received' (duration: 163.772997ms)","trace[2110072560] 'applied index is now lower than readState.Index' (duration: 14.573863ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:23:55.755436Z","caller":"traceutil/trace.go:171","msg":"trace[1258060554] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"187.530784ms","start":"2026-05-21T19:23:55.567895Z","end":"2026-05-21T19:23:55.755425Z","steps":["trace[1258060554] 'process raft request' (duration: 187.103279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:23:55.755677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.900056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:23:55.755732Z","caller":"traceutil/trace.go:171","msg":"trace[1377414217] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1363; }","duration":"178.988667ms","start":"2026-05-21T19:23:55.576733Z","end":"2026-05-21T19:23:55.755721Z","steps":["trace[1377414217] 'agreement among raft nodes before linearized reading' (duration: 178.901546ms)"],"step_count":1}