{"level":"warn","ts":"2026-05-05T09:44:15.277885Z","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-05T09:44:15.277986Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-05T09:44:15.278075Z","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-05T09:44:15.27809Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-05T09:44:15.27813Z","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-05T09:44:15.278854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-05-05T09:44:15.278966Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-05T09:44:15.372496Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"93.236354ms"} {"level":"info","ts":"2026-05-05T09:44:15.409147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-05-05T09:44:15.409303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:44:15.409362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-05-05T09:44:15.40939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:44:15.409418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-05-05T09:44:15.409492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-05-05T09:44:15.43156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:44:15.437227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:44:15.44217Z","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-05T09:44:15.445489Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:44:15.449826Z","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-05T09:44:15.450642Z","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-05T09:44:15.450687Z","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-05T09:44:15.451018Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:44:15.452356Z","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-05T09:44:15.452414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-05T09:44:15.452548Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-05T09:44:15.452873Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:44:15.453009Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:44:15.458708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-05-05T09:44:15.458823Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-05T09:44:16.009975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:44:16.010048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:44:16.010108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-05-05T09:44:16.010128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:44:16.010139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-05T09:44:16.01017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-05-05T09:44:16.010192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-05T09:44:16.014679Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:44:16.015461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:44:16.015497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:44:16.015823Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:44:16.017457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:44:16.017508Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:44:16.017852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:44:16.018765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-05-05T09:44:16.03685Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:44:16.036952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:44:16.036985Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:44:18.730696Z","caller":"traceutil/trace.go:171","msg":"trace[116326918] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"143.600295ms","start":"2026-05-05T09:44:18.587055Z","end":"2026-05-05T09:44:18.730655Z","steps":["trace[116326918] 'process raft request' (duration: 63.591224ms)","trace[116326918] 'compare' (duration: 79.77034ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:18.898459Z","caller":"traceutil/trace.go:171","msg":"trace[77477537] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"164.308093ms","start":"2026-05-05T09:44:18.734115Z","end":"2026-05-05T09:44:18.898423Z","steps":["trace[77477537] 'process raft request' (duration: 121.095334ms)","trace[77477537] 'compare' (duration: 43.057438ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:18.96061Z","caller":"traceutil/trace.go:171","msg":"trace[1435597497] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"226.344106ms","start":"2026-05-05T09:44:18.734228Z","end":"2026-05-05T09:44:18.960572Z","steps":["trace[1435597497] 'process raft request' (duration: 225.983613ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:18.960577Z","caller":"traceutil/trace.go:171","msg":"trace[2017646432] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"223.535126ms","start":"2026-05-05T09:44:18.736978Z","end":"2026-05-05T09:44:18.960513Z","steps":["trace[2017646432] 'process raft request' (duration: 223.475386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:19.851264Z","caller":"traceutil/trace.go:171","msg":"trace[1154972679] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"120.579467ms","start":"2026-05-05T09:44:19.730654Z","end":"2026-05-05T09:44:19.851233Z","steps":["trace[1154972679] 'process raft request' (duration: 77.940974ms)","trace[1154972679] 'compare' (duration: 42.474782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:19.851247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.851027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-05-05T09:44:19.851383Z","caller":"traceutil/trace.go:171","msg":"trace[765095257] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:107; }","duration":"122.067108ms","start":"2026-05-05T09:44:19.729289Z","end":"2026-05-05T09:44:19.851356Z","steps":["trace[765095257] 'range keys from in-memory index tree' (duration: 121.668406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:20.035296Z","caller":"traceutil/trace.go:171","msg":"trace[1294925090] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"148.714918ms","start":"2026-05-05T09:44:19.886546Z","end":"2026-05-05T09:44:20.03526Z","steps":["trace[1294925090] 'read index received' (duration: 82.468488ms)","trace[1294925090] 'applied index is now lower than readState.Index' (duration: 66.24498ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:20.03542Z","caller":"traceutil/trace.go:171","msg":"trace[2063659825] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"176.105591ms","start":"2026-05-05T09:44:19.859268Z","end":"2026-05-05T09:44:20.035374Z","steps":["trace[2063659825] 'process raft request' (duration: 109.7813ms)","trace[2063659825] 'compare' (duration: 66.009838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:20.035632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.03247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:44:20.035771Z","caller":"traceutil/trace.go:171","msg":"trace[872475669] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:110; }","duration":"149.220131ms","start":"2026-05-05T09:44:19.886513Z","end":"2026-05-05T09:44:20.035733Z","steps":["trace[872475669] 'agreement among raft nodes before linearized reading' (duration: 148.931479ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:20.315442Z","caller":"traceutil/trace.go:171","msg":"trace[1461443089] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"181.311804ms","start":"2026-05-05T09:44:20.134083Z","end":"2026-05-05T09:44:20.315395Z","steps":["trace[1461443089] 'read index received' (duration: 87.260018ms)","trace[1461443089] 'applied index is now lower than readState.Index' (duration: 94.049806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:20.315793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.681397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T09:44:20.315905Z","caller":"traceutil/trace.go:171","msg":"trace[1017075581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:114; }","duration":"181.850118ms","start":"2026-05-05T09:44:20.134031Z","end":"2026-05-05T09:44:20.315882Z","steps":["trace[1017075581] 'agreement among raft nodes before linearized reading' (duration: 181.530945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:20.316243Z","caller":"traceutil/trace.go:171","msg":"trace[2079577905] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"211.563178ms","start":"2026-05-05T09:44:20.104645Z","end":"2026-05-05T09:44:20.316209Z","steps":["trace[2079577905] 'process raft request' (duration: 116.774417ms)","trace[2079577905] 'compare' (duration: 93.761063ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:20.542998Z","caller":"traceutil/trace.go:171","msg":"trace[12640485] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"178.371033ms","start":"2026-05-05T09:44:20.36459Z","end":"2026-05-05T09:44:20.542961Z","steps":["trace[12640485] 'process raft request' (duration: 82.00899ms)","trace[12640485] 'compare' (duration: 96.183382ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:20.802776Z","caller":"traceutil/trace.go:171","msg":"trace[1476134708] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"250.345372ms","start":"2026-05-05T09:44:20.552387Z","end":"2026-05-05T09:44:20.802732Z","steps":["trace[1476134708] 'process raft request' (duration: 244.33399ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:20.803058Z","caller":"traceutil/trace.go:171","msg":"trace[228416592] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"155.443761ms","start":"2026-05-05T09:44:20.647564Z","end":"2026-05-05T09:44:20.803008Z","steps":["trace[228416592] 'read index received' (duration: 149.209726ms)","trace[228416592] 'applied index is now lower than readState.Index' (duration: 6.229095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:20.803287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.689533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:44:20.803635Z","caller":"traceutil/trace.go:171","msg":"trace[2090093747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"156.104206ms","start":"2026-05-05T09:44:20.64751Z","end":"2026-05-05T09:44:20.803614Z","steps":["trace[2090093747] 'agreement among raft nodes before linearized reading' (duration: 155.615832ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:21.086566Z","caller":"traceutil/trace.go:171","msg":"trace[1445518041] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"204.265424ms","start":"2026-05-05T09:44:20.882262Z","end":"2026-05-05T09:44:21.086527Z","steps":["trace[1445518041] 'process raft request' (duration: 204.056602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:21.367445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.526815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T09:44:21.367574Z","caller":"traceutil/trace.go:171","msg":"trace[1297119724] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"249.006066ms","start":"2026-05-05T09:44:21.118548Z","end":"2026-05-05T09:44:21.367554Z","steps":["trace[1297119724] 'process raft request' (duration: 47.855997ms)","trace[1297119724] 'compare' (duration: 200.363974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:21.625806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.710645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:44:21.625929Z","caller":"traceutil/trace.go:171","msg":"trace[190822084] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:0; response_revision:125; }","duration":"156.911346ms","start":"2026-05-05T09:44:21.468986Z","end":"2026-05-05T09:44:21.625897Z","steps":["trace[190822084] 'range keys from in-memory index tree' (duration: 156.587544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:21.838982Z","caller":"traceutil/trace.go:171","msg":"trace[308846655] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"116.527671ms","start":"2026-05-05T09:44:21.72241Z","end":"2026-05-05T09:44:21.838938Z","steps":["trace[308846655] 'process raft request' (duration: 43.193754ms)","trace[308846655] 'compare' (duration: 72.814944ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:21.839035Z","caller":"traceutil/trace.go:171","msg":"trace[807565001] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"113.932323ms","start":"2026-05-05T09:44:21.725065Z","end":"2026-05-05T09:44:21.838997Z","steps":["trace[807565001] 'process raft request' (duration: 113.721141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:22.251766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.831111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:22.252099Z","caller":"traceutil/trace.go:171","msg":"trace[840319878] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"326.331065ms","start":"2026-05-05T09:44:21.925725Z","end":"2026-05-05T09:44:22.252056Z","steps":["trace[840319878] 'process raft request' (duration: 68.130161ms)","trace[840319878] 'compare' (duration: 257.60115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:22.252278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:21.925717Z","time spent":"326.477285ms","remote":"127.0.0.1:48500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:22.508453Z","caller":"traceutil/trace.go:171","msg":"trace[796259249] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"186.944853ms","start":"2026-05-05T09:44:22.321467Z","end":"2026-05-05T09:44:22.508412Z","steps":["trace[796259249] 'process raft request' (duration: 166.263718ms)","trace[796259249] 'compare' (duration: 20.508553ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:22.577263Z","caller":"traceutil/trace.go:171","msg":"trace[1559069111] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:139; }","duration":"220.514229ms","start":"2026-05-05T09:44:22.356715Z","end":"2026-05-05T09:44:22.577229Z","steps":["trace[1559069111] 'read index received' (duration: 131.035701ms)","trace[1559069111] 'applied index is now lower than readState.Index' (duration: 89.477548ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:22.577389Z","caller":"traceutil/trace.go:171","msg":"trace[1874410008] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"255.214213ms","start":"2026-05-05T09:44:22.322069Z","end":"2026-05-05T09:44:22.577283Z","steps":["trace[1874410008] 'process raft request' (duration: 254.992981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:22.57764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.883612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T09:44:22.577759Z","caller":"traceutil/trace.go:171","msg":"trace[706817649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:136; }","duration":"221.065173ms","start":"2026-05-05T09:44:22.356668Z","end":"2026-05-05T09:44:22.577733Z","steps":["trace[706817649] 'agreement among raft nodes before linearized reading' (duration: 220.732541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:22.764339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.664994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:22.764795Z","caller":"traceutil/trace.go:171","msg":"trace[1686233027] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"183.049796ms","start":"2026-05-05T09:44:22.581689Z","end":"2026-05-05T09:44:22.764739Z","steps":["trace[1686233027] 'process raft request' (duration: 182.916945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:22.765101Z","caller":"traceutil/trace.go:171","msg":"trace[1955683270] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"182.1614ms","start":"2026-05-05T09:44:22.582921Z","end":"2026-05-05T09:44:22.765082Z","steps":["trace[1955683270] 'process raft request' (duration: 181.725057ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:22.765213Z","caller":"traceutil/trace.go:171","msg":"trace[1123778064] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"183.783152ms","start":"2026-05-05T09:44:22.581369Z","end":"2026-05-05T09:44:22.765152Z","steps":["trace[1123778064] 'process raft request' (duration: 27.228882ms)","trace[1123778064] 'compare' (duration: 155.526573ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:22.983369Z","caller":"traceutil/trace.go:171","msg":"trace[1948729292] linearizableReadLoop","detail":"{readStateIndex:147; appliedIndex:146; }","duration":"119.940272ms","start":"2026-05-05T09:44:22.863405Z","end":"2026-05-05T09:44:22.983345Z","steps":["trace[1948729292] 'read index received' (duration: 34.534102ms)","trace[1948729292] 'applied index is now lower than readState.Index' (duration: 85.40254ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:22.983478Z","caller":"traceutil/trace.go:171","msg":"trace[1505204724] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"123.305756ms","start":"2026-05-05T09:44:22.860115Z","end":"2026-05-05T09:44:22.98342Z","steps":["trace[1505204724] 'process raft request' (duration: 37.862855ms)","trace[1505204724] 'compare' (duration: 85.019118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:22.983628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.082084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:22.983704Z","caller":"traceutil/trace.go:171","msg":"trace[638613199] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:142; }","duration":"120.337576ms","start":"2026-05-05T09:44:22.863351Z","end":"2026-05-05T09:44:22.983688Z","steps":["trace[638613199] 'agreement among raft nodes before linearized reading' (duration: 120.081134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:23.204532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.373276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:23.204661Z","caller":"traceutil/trace.go:171","msg":"trace[778134839] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"135.144645ms","start":"2026-05-05T09:44:23.069499Z","end":"2026-05-05T09:44:23.204644Z","steps":["trace[778134839] 'read index received' (duration: 22.386966ms)","trace[778134839] 'applied index is now lower than readState.Index' (duration: 112.756249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:23.204738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.252196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:23.20476Z","caller":"traceutil/trace.go:171","msg":"trace[378548344] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:0; response_revision:144; }","duration":"135.325997ms","start":"2026-05-05T09:44:23.069427Z","end":"2026-05-05T09:44:23.204753Z","steps":["trace[378548344] 'agreement among raft nodes before linearized reading' (duration: 135.259896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:23.204748Z","caller":"traceutil/trace.go:171","msg":"trace[2060635139] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"211.808062ms","start":"2026-05-05T09:44:22.992889Z","end":"2026-05-05T09:44:23.204697Z","steps":["trace[2060635139] 'process raft request' (duration: 99.028594ms)","trace[2060635139] 'compare' (duration: 112.233775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:23.434245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.244327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:23.434612Z","caller":"traceutil/trace.go:171","msg":"trace[87657837] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"157.16492ms","start":"2026-05-05T09:44:23.277379Z","end":"2026-05-05T09:44:23.434544Z","steps":["trace[87657837] 'process raft request' (duration: 51.48135ms)","trace[87657837] 'compare' (duration: 105.023156ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:23.434668Z","caller":"traceutil/trace.go:171","msg":"trace[661804653] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"154.696432ms","start":"2026-05-05T09:44:23.279889Z","end":"2026-05-05T09:44:23.434585Z","steps":["trace[661804653] 'process raft request' (duration: 154.499631ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:23.853223Z","caller":"traceutil/trace.go:171","msg":"trace[605466900] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"109.514736ms","start":"2026-05-05T09:44:23.743661Z","end":"2026-05-05T09:44:23.853176Z","steps":["trace[605466900] 'process raft request' (duration: 22.543807ms)","trace[605466900] 'compare' (duration: 86.699717ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:23.853227Z","caller":"traceutil/trace.go:171","msg":"trace[320658747] linearizableReadLoop","detail":"{readStateIndex:214; appliedIndex:213; }","duration":"106.738937ms","start":"2026-05-05T09:44:23.746428Z","end":"2026-05-05T09:44:23.853167Z","steps":["trace[320658747] 'read index received' (duration: 19.787888ms)","trace[320658747] 'applied index is now lower than readState.Index' (duration: 86.948639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:23.853688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.207801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:23.853792Z","caller":"traceutil/trace.go:171","msg":"trace[1949840401] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:0; response_revision:209; }","duration":"107.387342ms","start":"2026-05-05T09:44:23.746383Z","end":"2026-05-05T09:44:23.853771Z","steps":["trace[1949840401] 'agreement among raft nodes before linearized reading' (duration: 107.06344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:24.166816Z","caller":"traceutil/trace.go:171","msg":"trace[663525445] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"102.845563ms","start":"2026-05-05T09:44:24.063926Z","end":"2026-05-05T09:44:24.166771Z","steps":["trace[663525445] 'process raft request' (duration: 36.945131ms)","trace[663525445] 'compare' (duration: 65.458953ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:26.549907Z","caller":"traceutil/trace.go:171","msg":"trace[1411463812] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"103.591689ms","start":"2026-05-05T09:44:26.446285Z","end":"2026-05-05T09:44:26.549877Z","steps":["trace[1411463812] 'process raft request' (duration: 103.518185ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:26.55006Z","caller":"traceutil/trace.go:171","msg":"trace[529442889] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"118.66284ms","start":"2026-05-05T09:44:26.431347Z","end":"2026-05-05T09:44:26.55001Z","steps":["trace[529442889] 'process raft request' (duration: 48.364328ms)","trace[529442889] 'compare' (duration: 69.840343ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.070008Z","caller":"traceutil/trace.go:171","msg":"trace[1168632493] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"228.761645ms","start":"2026-05-05T09:44:29.841218Z","end":"2026-05-05T09:44:30.06998Z","steps":["trace[1168632493] 'process raft request' (duration: 188.023491ms)","trace[1168632493] 'compare' (duration: 40.479283ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.07034Z","caller":"traceutil/trace.go:171","msg":"trace[1926763162] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"228.787136ms","start":"2026-05-05T09:44:29.84153Z","end":"2026-05-05T09:44:30.070317Z","steps":["trace[1926763162] 'process raft request' (duration: 228.39808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.070339Z","caller":"traceutil/trace.go:171","msg":"trace[1758258484] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"190.206881ms","start":"2026-05-05T09:44:29.880101Z","end":"2026-05-05T09:44:30.070308Z","steps":["trace[1758258484] 'read index received' (duration: 24.774938ms)","trace[1758258484] 'applied index is now lower than readState.Index' (duration: 165.429083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:30.070524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.403989ms","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-05T09:44:30.070592Z","caller":"traceutil/trace.go:171","msg":"trace[1487873072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:360; }","duration":"190.510573ms","start":"2026-05-05T09:44:29.880064Z","end":"2026-05-05T09:44:30.070574Z","steps":["trace[1487873072] 'agreement among raft nodes before linearized reading' (duration: 190.357897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.071086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.327114ms","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-05T09:44:30.071177Z","caller":"traceutil/trace.go:171","msg":"trace[533946363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:360; }","duration":"179.446949ms","start":"2026-05-05T09:44:29.891688Z","end":"2026-05-05T09:44:30.071135Z","steps":["trace[533946363] 'agreement among raft nodes before linearized reading' (duration: 179.318724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.071431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.904172ms","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-05T09:44:30.071476Z","caller":"traceutil/trace.go:171","msg":"trace[1971008112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"111.013048ms","start":"2026-05-05T09:44:29.960449Z","end":"2026-05-05T09:44:30.071463Z","steps":["trace[1971008112] 'agreement among raft nodes before linearized reading' (duration: 110.929215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.071684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.953216ms","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-05T09:44:30.071726Z","caller":"traceutil/trace.go:171","msg":"trace[276736436] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:360; }","duration":"141.02573ms","start":"2026-05-05T09:44:29.930688Z","end":"2026-05-05T09:44:30.071714Z","steps":["trace[276736436] 'agreement among raft nodes before linearized reading' (duration: 140.950787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.071896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.781277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:30.071936Z","caller":"traceutil/trace.go:171","msg":"trace[1070446295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:360; }","duration":"147.867141ms","start":"2026-05-05T09:44:29.924056Z","end":"2026-05-05T09:44:30.071923Z","steps":["trace[1070446295] 'agreement among raft nodes before linearized reading' (duration: 147.798389ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.343753Z","caller":"traceutil/trace.go:171","msg":"trace[282008350] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"264.781248ms","start":"2026-05-05T09:44:30.078936Z","end":"2026-05-05T09:44:30.343717Z","steps":["trace[282008350] 'process raft request' (duration: 169.917967ms)","trace[282008350] 'compare' (duration: 94.609471ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.34394Z","caller":"traceutil/trace.go:171","msg":"trace[2108807561] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"262.479705ms","start":"2026-05-05T09:44:30.081444Z","end":"2026-05-05T09:44:30.343923Z","steps":["trace[2108807561] 'process raft request' (duration: 262.213434ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.34422Z","caller":"traceutil/trace.go:171","msg":"trace[94434837] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"262.551368ms","start":"2026-05-05T09:44:30.081644Z","end":"2026-05-05T09:44:30.344195Z","steps":["trace[94434837] 'process raft request' (duration: 262.210424ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.344502Z","caller":"traceutil/trace.go:171","msg":"trace[1378990352] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"262.811229ms","start":"2026-05-05T09:44:30.081677Z","end":"2026-05-05T09:44:30.344488Z","steps":["trace[1378990352] 'process raft request' (duration: 262.451744ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.344624Z","caller":"traceutil/trace.go:171","msg":"trace[285363277] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"261.626442ms","start":"2026-05-05T09:44:30.082976Z","end":"2026-05-05T09:44:30.344602Z","steps":["trace[285363277] 'process raft request' (duration: 261.277997ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.344739Z","caller":"traceutil/trace.go:171","msg":"trace[1725446984] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"259.946222ms","start":"2026-05-05T09:44:30.084768Z","end":"2026-05-05T09:44:30.344714Z","steps":["trace[1725446984] 'process raft request' (duration: 259.720523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.345646Z","caller":"traceutil/trace.go:171","msg":"trace[86522812] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:372; }","duration":"263.864362ms","start":"2026-05-05T09:44:30.081759Z","end":"2026-05-05T09:44:30.345624Z","steps":["trace[86522812] 'read index received' (duration: 67.531952ms)","trace[86522812] 'applied index is now lower than readState.Index' (duration: 196.33077ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.3458Z","caller":"traceutil/trace.go:171","msg":"trace[1553363297] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"260.62951ms","start":"2026-05-05T09:44:30.085155Z","end":"2026-05-05T09:44:30.345784Z","steps":["trace[1553363297] 'process raft request' (duration: 260.314798ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.346038Z","caller":"traceutil/trace.go:171","msg":"trace[358157015] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"261.063998ms","start":"2026-05-05T09:44:30.084957Z","end":"2026-05-05T09:44:30.346021Z","steps":["trace[358157015] 'process raft request' (duration: 259.61465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.346665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.879653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-05T09:44:30.346743Z","caller":"traceutil/trace.go:171","msg":"trace[422233100] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:370; }","duration":"264.992027ms","start":"2026-05-05T09:44:30.08173Z","end":"2026-05-05T09:44:30.346722Z","steps":["trace[422233100] 'agreement among raft nodes before linearized reading' (duration: 264.858922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.347641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.847443ms","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-05T09:44:30.347711Z","caller":"traceutil/trace.go:171","msg":"trace[431870189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:370; }","duration":"265.938096ms","start":"2026-05-05T09:44:30.081755Z","end":"2026-05-05T09:44:30.347693Z","steps":["trace[431870189] 'agreement among raft nodes before linearized reading' (duration: 265.814121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.34792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.670127ms","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-05T09:44:30.348037Z","caller":"traceutil/trace.go:171","msg":"trace[678167714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:370; }","duration":"116.858014ms","start":"2026-05-05T09:44:30.231156Z","end":"2026-05-05T09:44:30.348014Z","steps":["trace[678167714] 'agreement among raft nodes before linearized reading' (duration: 116.536481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.34822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.786582ms","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-05T09:44:30.348409Z","caller":"traceutil/trace.go:171","msg":"trace[270819021] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"260.895831ms","start":"2026-05-05T09:44:30.08567Z","end":"2026-05-05T09:44:30.346566Z","steps":["trace[270819021] 'process raft request' (duration: 259.87689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.348643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.306132ms","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-05T09:44:30.348698Z","caller":"traceutil/trace.go:171","msg":"trace[192629180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:370; }","duration":"168.410396ms","start":"2026-05-05T09:44:30.180275Z","end":"2026-05-05T09:44:30.348685Z","steps":["trace[192629180] 'agreement among raft nodes before linearized reading' (duration: 167.74746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.349456Z","caller":"traceutil/trace.go:171","msg":"trace[1666602198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:370; }","duration":"217.897806ms","start":"2026-05-05T09:44:30.130376Z","end":"2026-05-05T09:44:30.348274Z","steps":["trace[1666602198] 'agreement among raft nodes before linearized reading' (duration: 217.727509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.607152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.381793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:30.607509Z","caller":"traceutil/trace.go:171","msg":"trace[155261223] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"251.323363ms","start":"2026-05-05T09:44:30.356121Z","end":"2026-05-05T09:44:30.607445Z","steps":["trace[155261223] 'process raft request' (duration: 120.557995ms)","trace[155261223] 'compare' (duration: 130.247887ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.607577Z","caller":"traceutil/trace.go:171","msg":"trace[58648861] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:382; }","duration":"246.000216ms","start":"2026-05-05T09:44:30.361559Z","end":"2026-05-05T09:44:30.607559Z","steps":["trace[58648861] 'read index received' (duration: 9.158022ms)","trace[58648861] 'applied index is now lower than readState.Index' (duration: 236.838414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:30.607714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.154523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-05-05T09:44:30.607698Z","caller":"traceutil/trace.go:171","msg":"trace[411153598] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"246.370962ms","start":"2026-05-05T09:44:30.361309Z","end":"2026-05-05T09:44:30.607679Z","steps":["trace[411153598] 'process raft request' (duration: 246.018407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.607879Z","caller":"traceutil/trace.go:171","msg":"trace[1024873703] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"246.453645ms","start":"2026-05-05T09:44:30.361421Z","end":"2026-05-05T09:44:30.607874Z","steps":["trace[1024873703] 'process raft request' (duration: 245.982465ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.607759Z","caller":"traceutil/trace.go:171","msg":"trace[2070661604] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:376; }","duration":"246.224146ms","start":"2026-05-05T09:44:30.361527Z","end":"2026-05-05T09:44:30.607751Z","steps":["trace[2070661604] 'agreement among raft nodes before linearized reading' (duration: 246.133622ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.607792Z","caller":"traceutil/trace.go:171","msg":"trace[557494484] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"246.361902ms","start":"2026-05-05T09:44:30.361424Z","end":"2026-05-05T09:44:30.607786Z","steps":["trace[557494484] 'process raft request' (duration: 245.950924ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.608081Z","caller":"traceutil/trace.go:171","msg":"trace[1142306356] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"244.841691ms","start":"2026-05-05T09:44:30.363194Z","end":"2026-05-05T09:44:30.608035Z","steps":["trace[1142306356] 'process raft request' (duration: 244.240825ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.608187Z","caller":"traceutil/trace.go:171","msg":"trace[250333247] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"227.561459ms","start":"2026-05-05T09:44:30.380611Z","end":"2026-05-05T09:44:30.608172Z","steps":["trace[250333247] 'process raft request' (duration: 227.516227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.608235Z","caller":"traceutil/trace.go:171","msg":"trace[1129372934] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"232.231398ms","start":"2026-05-05T09:44:30.375993Z","end":"2026-05-05T09:44:30.608225Z","steps":["trace[1129372934] 'process raft request' (duration: 231.967158ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:30.870874Z","caller":"traceutil/trace.go:171","msg":"trace[1933541984] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"253.528032ms","start":"2026-05-05T09:44:30.617295Z","end":"2026-05-05T09:44:30.870823Z","steps":["trace[1933541984] 'process raft request' (duration: 173.4211ms)","trace[1933541984] 'compare' (duration: 79.768058ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.871118Z","caller":"traceutil/trace.go:171","msg":"trace[1584369690] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:390; }","duration":"253.47331ms","start":"2026-05-05T09:44:30.617628Z","end":"2026-05-05T09:44:30.871101Z","steps":["trace[1584369690] 'read index received' (duration: 73.985363ms)","trace[1584369690] 'applied index is now lower than readState.Index' (duration: 179.486127ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:30.87131Z","caller":"traceutil/trace.go:171","msg":"trace[962706998] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"253.799413ms","start":"2026-05-05T09:44:30.617362Z","end":"2026-05-05T09:44:30.871161Z","steps":["trace[962706998] 'process raft request' (duration: 253.369465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:30.87152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.787363ms","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-05T09:44:30.87157Z","caller":"traceutil/trace.go:171","msg":"trace[392144946] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:381; }","duration":"253.983911ms","start":"2026-05-05T09:44:30.617574Z","end":"2026-05-05T09:44:30.871558Z","steps":["trace[392144946] 'agreement among raft nodes before linearized reading' (duration: 253.737751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.169701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.905354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:31.170068Z","caller":"traceutil/trace.go:171","msg":"trace[1858111651] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"292.553887ms","start":"2026-05-05T09:44:30.87743Z","end":"2026-05-05T09:44:31.169984Z","steps":["trace[1858111651] 'process raft request' (duration: 154.28947ms)","trace[1858111651] 'compare' (duration: 137.740776ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:31.170345Z","caller":"traceutil/trace.go:171","msg":"trace[1322224577] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"291.86383ms","start":"2026-05-05T09:44:30.878461Z","end":"2026-05-05T09:44:31.170325Z","steps":["trace[1322224577] 'process raft request' (duration: 291.350589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.170823Z","caller":"traceutil/trace.go:171","msg":"trace[687438662] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"292.377101ms","start":"2026-05-05T09:44:30.878419Z","end":"2026-05-05T09:44:31.170796Z","steps":["trace[687438662] 'read index received' (duration: 43.805929ms)","trace[687438662] 'applied index is now lower than readState.Index' (duration: 248.569912ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:31.17092Z","caller":"traceutil/trace.go:171","msg":"trace[1333726108] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"292.119721ms","start":"2026-05-05T09:44:30.878786Z","end":"2026-05-05T09:44:31.170905Z","steps":["trace[1333726108] 'process raft request' (duration: 291.406581ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.17101Z","caller":"traceutil/trace.go:171","msg":"trace[1557417003] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"291.600238ms","start":"2026-05-05T09:44:30.879394Z","end":"2026-05-05T09:44:31.170995Z","steps":["trace[1557417003] 'process raft request' (duration: 291.268314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.171117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.55965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:31.171216Z","caller":"traceutil/trace.go:171","msg":"trace[319650071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"245.725636ms","start":"2026-05-05T09:44:30.925469Z","end":"2026-05-05T09:44:31.171195Z","steps":["trace[319650071] 'agreement among raft nodes before linearized reading' (duration: 245.57589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.171331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.879061ms","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-05T09:44:31.171404Z","caller":"traceutil/trace.go:171","msg":"trace[1245149142] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:386; }","duration":"292.991045ms","start":"2026-05-05T09:44:30.8784Z","end":"2026-05-05T09:44:31.171391Z","steps":["trace[1245149142] 'agreement among raft nodes before linearized reading' (duration: 292.778987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.171667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.003981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:44:31.171792Z","caller":"traceutil/trace.go:171","msg":"trace[1073838794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"164.186748ms","start":"2026-05-05T09:44:31.007585Z","end":"2026-05-05T09:44:31.171772Z","steps":["trace[1073838794] 'agreement among raft nodes before linearized reading' (duration: 163.73264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.383605Z","caller":"traceutil/trace.go:171","msg":"trace[538936752] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"206.512365ms","start":"2026-05-05T09:44:31.177062Z","end":"2026-05-05T09:44:31.383575Z","steps":["trace[538936752] 'process raft request' (duration: 172.130485ms)","trace[538936752] 'compare' (duration: 34.14423ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:31.385851Z","caller":"traceutil/trace.go:171","msg":"trace[2010035616] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"207.960703ms","start":"2026-05-05T09:44:31.177794Z","end":"2026-05-05T09:44:31.385755Z","steps":["trace[2010035616] 'process raft request' (duration: 207.784276ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.385898Z","caller":"traceutil/trace.go:171","msg":"trace[2045168480] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"207.084289ms","start":"2026-05-05T09:44:31.178771Z","end":"2026-05-05T09:44:31.385856Z","steps":["trace[2045168480] 'process raft request' (duration: 206.875801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.386129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.298407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4wpqm\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-05T09:44:31.385902Z","caller":"traceutil/trace.go:171","msg":"trace[1831499949] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:398; }","duration":"208.145801ms","start":"2026-05-05T09:44:31.177706Z","end":"2026-05-05T09:44:31.385852Z","steps":["trace[1831499949] 'read index received' (duration: 169.96036ms)","trace[1831499949] 'applied index is now lower than readState.Index' (duration: 38.184551ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:31.386226Z","caller":"traceutil/trace.go:171","msg":"trace[9578693] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"209.023806ms","start":"2026-05-05T09:44:31.177182Z","end":"2026-05-05T09:44:31.386206Z","steps":["trace[9578693] 'process raft request' (duration: 208.291317ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.386218Z","caller":"traceutil/trace.go:171","msg":"trace[641182567] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"195.761803ms","start":"2026-05-05T09:44:31.190418Z","end":"2026-05-05T09:44:31.38618Z","steps":["trace[641182567] 'process raft request' (duration: 195.336726ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.386269Z","caller":"traceutil/trace.go:171","msg":"trace[140775691] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4wpqm; range_end:; response_count:1; response_revision:392; }","duration":"208.558167ms","start":"2026-05-05T09:44:31.177618Z","end":"2026-05-05T09:44:31.386176Z","steps":["trace[140775691] 'agreement among raft nodes before linearized reading' (duration: 208.325648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:31.386209Z","caller":"traceutil/trace.go:171","msg":"trace[233394143] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"204.440233ms","start":"2026-05-05T09:44:31.181725Z","end":"2026-05-05T09:44:31.386165Z","steps":["trace[233394143] 'process raft request' (duration: 203.986955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:31.386608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.319647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-05T09:44:31.386655Z","caller":"traceutil/trace.go:171","msg":"trace[1544839642] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:392; }","duration":"204.548186ms","start":"2026-05-05T09:44:31.182093Z","end":"2026-05-05T09:44:31.386641Z","steps":["trace[1544839642] 'agreement among raft nodes before linearized reading' (duration: 204.259614ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:37.154103Z","caller":"traceutil/trace.go:171","msg":"trace[1943042827] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"205.496856ms","start":"2026-05-05T09:44:36.948572Z","end":"2026-05-05T09:44:37.154069Z","steps":["trace[1943042827] 'process raft request' (duration: 205.308909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:37.717929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.586104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:44:37.718015Z","caller":"traceutil/trace.go:171","msg":"trace[664414936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"296.729709ms","start":"2026-05-05T09:44:37.421267Z","end":"2026-05-05T09:44:37.717997Z","steps":["trace[664414936] 'range keys from in-memory index tree' (duration: 296.48409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:45.575241Z","caller":"traceutil/trace.go:171","msg":"trace[1489261674] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"212.793574ms","start":"2026-05-05T09:44:45.362426Z","end":"2026-05-05T09:44:45.575219Z","steps":["trace[1489261674] 'read index received' (duration: 212.66318ms)","trace[1489261674] 'applied index is now lower than readState.Index' (duration: 129.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:45.575293Z","caller":"traceutil/trace.go:171","msg":"trace[360000351] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"379.321923ms","start":"2026-05-05T09:44:45.195952Z","end":"2026-05-05T09:44:45.575274Z","steps":["trace[360000351] 'process raft request' (duration: 379.159638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:45.575345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.907208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:45.575368Z","caller":"traceutil/trace.go:171","msg":"trace[1541291723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"212.947749ms","start":"2026-05-05T09:44:45.362413Z","end":"2026-05-05T09:44:45.575361Z","steps":["trace[1541291723] 'agreement among raft nodes before linearized reading' (duration: 212.890807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:45.575443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:45.195939Z","time spent":"379.406967ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:44:47.716692Z","caller":"traceutil/trace.go:171","msg":"trace[1239883438] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"133.519742ms","start":"2026-05-05T09:44:47.583143Z","end":"2026-05-05T09:44:47.716663Z","steps":["trace[1239883438] 'process raft request' (duration: 133.371947ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:55.787994Z","caller":"traceutil/trace.go:171","msg":"trace[289646805] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"589.469404ms","start":"2026-05-05T09:44:55.198214Z","end":"2026-05-05T09:44:55.787683Z","steps":["trace[289646805] 'process raft request' (duration: 589.237737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:55.788133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.198148Z","time spent":"589.919988ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328178Z","caller":"traceutil/trace.go:171","msg":"trace[1782357406] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.128247207s","start":"2026-05-05T09:44:55.1999Z","end":"2026-05-05T09:44:56.328147Z","steps":["trace[1782357406] 'process raft request' (duration: 1.12802875s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.328292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.199883Z","time spent":"1.128351779s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328386Z","caller":"traceutil/trace.go:171","msg":"trace[857429246] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.110578154s","start":"2026-05-05T09:44:55.217801Z","end":"2026-05-05T09:44:56.328379Z","steps":["trace[857429246] 'process raft request' (duration: 1.11046926s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.328418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.217794Z","time spent":"1.110609104s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328492Z","caller":"traceutil/trace.go:171","msg":"trace[1954729776] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.052784526s","start":"2026-05-05T09:44:55.275699Z","end":"2026-05-05T09:44:56.328484Z","steps":["trace[1954729776] 'process raft request' (duration: 1.052661462s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:56.328477Z","caller":"traceutil/trace.go:171","msg":"trace[68499113] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.052858539s","start":"2026-05-05T09:44:55.275582Z","end":"2026-05-05T09:44:56.328441Z","steps":["trace[68499113] 'process raft request' (duration: 1.052735895s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:56.328548Z","caller":"traceutil/trace.go:171","msg":"trace[104132350] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.052733675s","start":"2026-05-05T09:44:55.27581Z","end":"2026-05-05T09:44:56.328543Z","steps":["trace[104132350] 'process raft request' (duration: 1.052598981s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.328572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.275792Z","time spent":"1.052772625s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328463Z","caller":"traceutil/trace.go:171","msg":"trace[1700079482] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.111498981s","start":"2026-05-05T09:44:55.216864Z","end":"2026-05-05T09:44:56.328363Z","steps":["trace[1700079482] 'process raft request' (duration: 1.111252574s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:56.328671Z","caller":"traceutil/trace.go:171","msg":"trace[91964423] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.042104307s","start":"2026-05-05T09:44:55.286551Z","end":"2026-05-05T09:44:56.328655Z","steps":["trace[91964423] 'process raft request' (duration: 1.041924112s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.328811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.216847Z","time spent":"1.111794571s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:44:56.328926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.286534Z","time spent":"1.04218566s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328982Z","caller":"traceutil/trace.go:171","msg":"trace[1653599725] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.032400478s","start":"2026-05-05T09:44:55.296565Z","end":"2026-05-05T09:44:56.328965Z","steps":["trace[1653599725] 'process raft request' (duration: 1.031985966s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.328521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.275689Z","time spent":"1.052821848s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.328856Z","caller":"traceutil/trace.go:171","msg":"trace[40578899] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.119449987s","start":"2026-05-05T09:44:55.20876Z","end":"2026-05-05T09:44:56.32821Z","steps":["trace[40578899] 'process raft request' (duration: 1.119304882s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.329558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.208734Z","time spent":"1.120785796s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:44:56.329629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.173498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T09:44:56.329066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.296553Z","time spent":"1.032478511s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.329696Z","caller":"traceutil/trace.go:171","msg":"trace[1932944378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"571.443616ms","start":"2026-05-05T09:44:55.758234Z","end":"2026-05-05T09:44:56.329678Z","steps":["trace[1932944378] 'agreement among raft nodes before linearized reading' (duration: 570.973212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:56.329166Z","caller":"traceutil/trace.go:171","msg":"trace[675171964] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:550; }","duration":"570.860129ms","start":"2026-05-05T09:44:55.758281Z","end":"2026-05-05T09:44:56.329141Z","steps":["trace[675171964] 'read index received' (duration: 29.226859ms)","trace[675171964] 'applied index is now lower than readState.Index' (duration: 541.63197ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:56.329229Z","caller":"traceutil/trace.go:171","msg":"trace[30359264] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"928.792563ms","start":"2026-05-05T09:44:55.40043Z","end":"2026-05-05T09:44:56.329223Z","steps":["trace[30359264] 'process raft request' (duration: 928.604607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.329847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.400401Z","time spent":"929.419042ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.329228Z","caller":"traceutil/trace.go:171","msg":"trace[952582241] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.010997591s","start":"2026-05-05T09:44:55.318212Z","end":"2026-05-05T09:44:56.329209Z","steps":["trace[952582241] 'process raft request' (duration: 1.010511426s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.329988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.318195Z","time spent":"1.011712173s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:44:56.330044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.275556Z","time spent":"1.053294702s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:44:56.329745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.758168Z","time spent":"571.563979ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T09:44:56.332709Z","caller":"traceutil/trace.go:171","msg":"trace[1041614022] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"537.787701ms","start":"2026-05-05T09:44:55.794875Z","end":"2026-05-05T09:44:56.332662Z","steps":["trace[1041614022] 'process raft request' (duration: 537.397039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.332775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.745177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:44:56.332878Z","caller":"traceutil/trace.go:171","msg":"trace[1447839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"522.83531ms","start":"2026-05-05T09:44:55.810005Z","end":"2026-05-05T09:44:56.33284Z","steps":["trace[1447839] 'agreement among raft nodes before linearized reading' (duration: 522.677755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.332931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.809958Z","time spent":"522.962693ms","remote":"127.0.0.1:48408","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-05T09:44:56.333019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.794809Z","time spent":"538.009217ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:56.33319Z","caller":"traceutil/trace.go:171","msg":"trace[1454702535] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"536.094599ms","start":"2026-05-05T09:44:55.797079Z","end":"2026-05-05T09:44:56.333174Z","steps":["trace[1454702535] 'process raft request' (duration: 535.460259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.333303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.797028Z","time spent":"536.209782ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:44:56.333531Z","caller":"traceutil/trace.go:171","msg":"trace[109900029] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"536.13127ms","start":"2026-05-05T09:44:55.797386Z","end":"2026-05-05T09:44:56.333518Z","steps":["trace[109900029] 'process raft request' (duration: 535.245223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.333613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.79738Z","time spent":"536.192772ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:44:56.333711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.777623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:56.333801Z","caller":"traceutil/trace.go:171","msg":"trace[1175947806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"408.922438ms","start":"2026-05-05T09:44:55.924865Z","end":"2026-05-05T09:44:56.333788Z","steps":["trace[1175947806] 'agreement among raft nodes before linearized reading' (duration: 408.68572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:56.336056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:55.924844Z","time spent":"411.157356ms","remote":"127.0.0.1:48160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:44:57.156256Z","caller":"traceutil/trace.go:171","msg":"trace[1960755063] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"110.709813ms","start":"2026-05-05T09:44:57.045511Z","end":"2026-05-05T09:44:57.156221Z","steps":["trace[1960755063] 'process raft request' (duration: 109.448595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:57.667282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.448005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:57.667443Z","caller":"traceutil/trace.go:171","msg":"trace[1196235759] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"425.897975ms","start":"2026-05-05T09:44:57.241517Z","end":"2026-05-05T09:44:57.667415Z","steps":["trace[1196235759] 'process raft request' (duration: 245.198323ms)","trace[1196235759] 'compare' (duration: 180.149376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:57.667502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:57.241497Z","time spent":"425.984057ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:44:57.675859Z","caller":"traceutil/trace.go:171","msg":"trace[412453157] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"326.209554ms","start":"2026-05-05T09:44:57.349624Z","end":"2026-05-05T09:44:57.675833Z","steps":["trace[412453157] 'process raft request' (duration: 325.816122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:57.676046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:57.349595Z","time spent":"326.342538ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:44:58.003425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.02135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:58.003885Z","caller":"traceutil/trace.go:171","msg":"trace[718716068] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"148.396599ms","start":"2026-05-05T09:44:57.855466Z","end":"2026-05-05T09:44:58.003863Z","steps":["trace[718716068] 'process raft request' (duration: 148.182602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:58.004033Z","caller":"traceutil/trace.go:171","msg":"trace[1645245204] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"281.418244ms","start":"2026-05-05T09:44:57.722549Z","end":"2026-05-05T09:44:58.003967Z","steps":["trace[1645245204] 'process raft request' (duration: 116.706193ms)","trace[1645245204] 'compare' (duration: 163.792864ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:58.060099Z","caller":"traceutil/trace.go:171","msg":"trace[896835955] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:627; }","duration":"136.876165ms","start":"2026-05-05T09:44:57.923162Z","end":"2026-05-05T09:44:58.060038Z","steps":["trace[896835955] 'read index received' (duration: 80.278328ms)","trace[896835955] 'applied index is now lower than readState.Index' (duration: 56.596717ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:58.060222Z","caller":"traceutil/trace.go:171","msg":"trace[1234563420] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"169.949921ms","start":"2026-05-05T09:44:57.890257Z","end":"2026-05-05T09:44:58.060207Z","steps":["trace[1234563420] 'process raft request' (duration: 168.55251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:58.060563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.38505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:58.060596Z","caller":"traceutil/trace.go:171","msg":"trace[1098215179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"137.466242ms","start":"2026-05-05T09:44:57.92312Z","end":"2026-05-05T09:44:58.060586Z","steps":["trace[1098215179] 'agreement among raft nodes before linearized reading' (duration: 137.3936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:01.874732Z","caller":"traceutil/trace.go:171","msg":"trace[2733487] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"245.758114ms","start":"2026-05-05T09:45:01.62895Z","end":"2026-05-05T09:45:01.874708Z","steps":["trace[2733487] 'process raft request' (duration: 245.030762ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:02.549146Z","caller":"traceutil/trace.go:171","msg":"trace[1497429858] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"163.936371ms","start":"2026-05-05T09:45:02.38515Z","end":"2026-05-05T09:45:02.549087Z","steps":["trace[1497429858] 'read index received' (duration: 1.001098ms)","trace[1497429858] 'applied index is now lower than readState.Index' (duration: 162.934033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:02.549413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.249291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T09:45:02.54947Z","caller":"traceutil/trace.go:171","msg":"trace[1718619063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"164.392795ms","start":"2026-05-05T09:45:02.385063Z","end":"2026-05-05T09:45:02.549456Z","steps":["trace[1718619063] 'agreement among raft nodes before linearized reading' (duration: 164.163588ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:02.54995Z","caller":"traceutil/trace.go:171","msg":"trace[1286376138] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"165.551997ms","start":"2026-05-05T09:45:02.383631Z","end":"2026-05-05T09:45:02.549183Z","steps":["trace[1286376138] 'process raft request' (duration: 158.186327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:03.149278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.438732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:03.149375Z","caller":"traceutil/trace.go:171","msg":"trace[1315595045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"223.594646ms","start":"2026-05-05T09:45:02.925755Z","end":"2026-05-05T09:45:03.14935Z","steps":["trace[1315595045] 'range keys from in-memory index tree' (duration: 223.35557ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:03.415101Z","caller":"traceutil/trace.go:171","msg":"trace[1801445417] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"264.36846ms","start":"2026-05-05T09:45:03.150648Z","end":"2026-05-05T09:45:03.415017Z","steps":["trace[1801445417] 'read index received' (duration: 262.515857ms)","trace[1801445417] 'applied index is now lower than readState.Index' (duration: 1.851573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:03.415172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.501393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:03.41529Z","caller":"traceutil/trace.go:171","msg":"trace[941089675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"264.686878ms","start":"2026-05-05T09:45:03.150593Z","end":"2026-05-05T09:45:03.41528Z","steps":["trace[941089675] 'agreement among raft nodes before linearized reading' (duration: 264.493133ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:03.415213Z","caller":"traceutil/trace.go:171","msg":"trace[1986534997] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"334.25776ms","start":"2026-05-05T09:45:03.080798Z","end":"2026-05-05T09:45:03.415056Z","steps":["trace[1986534997] 'process raft request' (duration: 332.668275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:03.415734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:03.080763Z","time spent":"334.690282ms","remote":"127.0.0.1:48240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:03.694695Z","caller":"traceutil/trace.go:171","msg":"trace[1196666961] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"219.867338ms","start":"2026-05-05T09:45:03.474805Z","end":"2026-05-05T09:45:03.694672Z","steps":["trace[1196666961] 'process raft request' (duration: 219.706584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:04.672323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033149544377562768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:45:05.117223Z","caller":"traceutil/trace.go:171","msg":"trace[1368861397] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"945.688813ms","start":"2026-05-05T09:45:04.171508Z","end":"2026-05-05T09:45:05.117197Z","steps":["trace[1368861397] 'read index received' (duration: 944.431448ms)","trace[1368861397] 'applied index is now lower than readState.Index' (duration: 1.256325ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:05.117341Z","caller":"traceutil/trace.go:171","msg":"trace[495572897] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"974.938483ms","start":"2026-05-05T09:45:04.142392Z","end":"2026-05-05T09:45:05.117331Z","steps":["trace[495572897] 'process raft request' (duration: 973.859224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.117494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.142358Z","time spent":"975.025746ms","remote":"127.0.0.1:48240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:05.117827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.342157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:45:05.117906Z","caller":"traceutil/trace.go:171","msg":"trace[635785190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"723.47162ms","start":"2026-05-05T09:45:04.39441Z","end":"2026-05-05T09:45:05.117881Z","steps":["trace[635785190] 'agreement among raft nodes before linearized reading' (duration: 723.208383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.117951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.394389Z","time spent":"723.554302ms","remote":"127.0.0.1:48408","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-05T09:45:05.118017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.384588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T09:45:05.11807Z","caller":"traceutil/trace.go:171","msg":"trace[674142254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"477.502571ms","start":"2026-05-05T09:45:04.640552Z","end":"2026-05-05T09:45:05.118054Z","steps":["trace[674142254] 'agreement among raft nodes before linearized reading' (duration: 477.362327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.11811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.640492Z","time spent":"477.608834ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:45:05.118244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.736462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5mn6x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T09:45:05.118274Z","caller":"traceutil/trace.go:171","msg":"trace[1826144164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5mn6x; range_end:; response_count:1; response_revision:642; }","duration":"946.805154ms","start":"2026-05-05T09:45:04.17146Z","end":"2026-05-05T09:45:05.118265Z","steps":["trace[1826144164] 'agreement among raft nodes before linearized reading' (duration: 946.744312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.1183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.171435Z","time spent":"946.858986ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-5mn6x\" "} {"level":"warn","ts":"2026-05-05T09:45:05.118358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.889802ms","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-05T09:45:05.118394Z","caller":"traceutil/trace.go:171","msg":"trace[1187469012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"477.958694ms","start":"2026-05-05T09:45:04.640424Z","end":"2026-05-05T09:45:05.118383Z","steps":["trace[1187469012] 'agreement among raft nodes before linearized reading' (duration: 477.855211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.118481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.935948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:45:05.118485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.640401Z","time spent":"478.013746ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T09:45:05.118515Z","caller":"traceutil/trace.go:171","msg":"trace[1523731154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"194.018941ms","start":"2026-05-05T09:45:04.924486Z","end":"2026-05-05T09:45:05.118505Z","steps":["trace[1523731154] 'agreement among raft nodes before linearized reading' (duration: 193.955279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.118675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.3692ms","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-05T09:45:05.118706Z","caller":"traceutil/trace.go:171","msg":"trace[1841400186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"486.462863ms","start":"2026-05-05T09:45:04.632233Z","end":"2026-05-05T09:45:05.118696Z","steps":["trace[1841400186] 'agreement among raft nodes before linearized reading' (duration: 486.37353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.118734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.632211Z","time spent":"486.515224ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:45:05.118937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.639757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T09:45:05.118966Z","caller":"traceutil/trace.go:171","msg":"trace[271679319] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:642; }","duration":"568.766421ms","start":"2026-05-05T09:45:04.550191Z","end":"2026-05-05T09:45:05.118957Z","steps":["trace[271679319] 'agreement among raft nodes before linearized reading' (duration: 568.677189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:05.119014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:04.550147Z","time spent":"568.860883ms","remote":"127.0.0.1:48176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.200\" "} {"level":"warn","ts":"2026-05-05T09:45:05.3631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.326698ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-05T09:45:07.00322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107756091s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T09:45:07.742774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847071693s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:45:07.74329Z","caller":"traceutil/trace.go:171","msg":"trace[629150099] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:666; }","duration":"818.377847ms","start":"2026-05-05T09:45:06.92489Z","end":"2026-05-05T09:45:07.743267Z","steps":["trace[629150099] 'read index received' (duration: 78.502829ms)","trace[629150099] 'applied index is now lower than readState.Index' (duration: 739.873427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:07.74342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.53506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:07.743473Z","caller":"traceutil/trace.go:171","msg":"trace[1949087206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"818.630993ms","start":"2026-05-05T09:45:06.924828Z","end":"2026-05-05T09:45:07.743459Z","steps":["trace[1949087206] 'agreement among raft nodes before linearized reading' (duration: 818.51701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.743518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:06.924803Z","time spent":"818.704155ms","remote":"127.0.0.1:48160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:45:07.743503Z","caller":"traceutil/trace.go:171","msg":"trace[1047997916] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.616275815s","start":"2026-05-05T09:45:05.127169Z","end":"2026-05-05T09:45:07.743445Z","steps":["trace[1047997916] 'process raft request' (duration: 2.615839353s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.743688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.127142Z","time spent":"2.61644465s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.743823Z","caller":"traceutil/trace.go:171","msg":"trace[235690359] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"2.612073389s","start":"2026-05-05T09:45:05.131734Z","end":"2026-05-05T09:45:07.743808Z","steps":["trace[235690359] 'process raft request' (duration: 2.61135668s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.74389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.131708Z","time spent":"2.61214414s","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.743904Z","caller":"traceutil/trace.go:171","msg":"trace[1540448665] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.379592722s","start":"2026-05-05T09:45:05.364294Z","end":"2026-05-05T09:45:07.743887Z","steps":["trace[1540448665] 'process raft request' (duration: 2.378932504s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.743974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.364272Z","time spent":"2.379665355s","remote":"127.0.0.1:48176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.743997Z","caller":"traceutil/trace.go:171","msg":"trace[232379981] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.620539594s","start":"2026-05-05T09:45:05.123442Z","end":"2026-05-05T09:45:07.743981Z","steps":["trace[232379981] 'process raft request' (duration: 772.151464ms)","trace[232379981] 'compare' (duration: 1.846835527s)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:07.744048Z","caller":"traceutil/trace.go:171","msg":"trace[166462218] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"2.562216843s","start":"2026-05-05T09:45:05.181821Z","end":"2026-05-05T09:45:07.744037Z","steps":["trace[166462218] 'process raft request' (duration: 2.56135972s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.74407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.123421Z","time spent":"2.620610406s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.744072Z","caller":"traceutil/trace.go:171","msg":"trace[32912356] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.618496907s","start":"2026-05-05T09:45:05.125559Z","end":"2026-05-05T09:45:07.744056Z","steps":["trace[32912356] 'process raft request' (duration: 2.617334364s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.7441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.181783Z","time spent":"2.562289575s","remote":"127.0.0.1:48634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:45:07.744146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.125552Z","time spent":"2.618557148s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.744411Z","caller":"traceutil/trace.go:171","msg":"trace[345962455] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.617255862s","start":"2026-05-05T09:45:05.127143Z","end":"2026-05-05T09:45:07.744399Z","steps":["trace[345962455] 'process raft request' (duration: 2.615812792s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:07.744481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:05.127112Z","time spent":"2.617332673s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:07.756771Z","caller":"traceutil/trace.go:171","msg":"trace[46678320] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"201.221198ms","start":"2026-05-05T09:45:07.555534Z","end":"2026-05-05T09:45:07.756755Z","steps":["trace[46678320] 'process raft request' (duration: 200.980482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:08.686778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033149544377562787,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:45:08.896296Z","caller":"traceutil/trace.go:171","msg":"trace[1177784185] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"726.605234ms","start":"2026-05-05T09:45:08.169139Z","end":"2026-05-05T09:45:08.895745Z","steps":["trace[1177784185] 'process raft request' (duration: 720.316304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:08.896477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:08.169117Z","time spent":"727.267011ms","remote":"127.0.0.1:48240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:08.897438Z","caller":"traceutil/trace.go:171","msg":"trace[1045303375] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"711.534977ms","start":"2026-05-05T09:45:08.185852Z","end":"2026-05-05T09:45:08.897387Z","steps":["trace[1045303375] 'read index received' (duration: 702.892623ms)","trace[1045303375] 'applied index is now lower than readState.Index' (duration: 8.639364ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:08.89747Z","caller":"traceutil/trace.go:171","msg":"trace[1518460083] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"709.523134ms","start":"2026-05-05T09:45:08.187911Z","end":"2026-05-05T09:45:08.897434Z","steps":["trace[1518460083] 'process raft request' (duration: 707.670234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:08.897623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.756114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5mn6x\" ","response":"range_response_count:1 size:17149"} {"level":"warn","ts":"2026-05-05T09:45:08.897653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:08.1879Z","time spent":"709.712989ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:45:08.897681Z","caller":"traceutil/trace.go:171","msg":"trace[1911140090] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5mn6x; range_end:; response_count:1; response_revision:652; }","duration":"711.849946ms","start":"2026-05-05T09:45:08.185818Z","end":"2026-05-05T09:45:08.897668Z","steps":["trace[1911140090] 'agreement among raft nodes before linearized reading' (duration: 711.678451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:08.897728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:08.185798Z","time spent":"711.921398ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17171,"request content":"key:\"/registry/pods/kube-system/cilium-5mn6x\" "} {"level":"info","ts":"2026-05-05T09:45:09.452756Z","caller":"traceutil/trace.go:171","msg":"trace[1243374200] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"446.156595ms","start":"2026-05-05T09:45:09.006572Z","end":"2026-05-05T09:45:09.452729Z","steps":["trace[1243374200] 'process raft request' (duration: 396.039817ms)","trace[1243374200] 'compare' (duration: 49.701167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:09.452882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.006547Z","time spent":"446.276158ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:45:09.673504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.382819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:45:09.673849Z","caller":"traceutil/trace.go:171","msg":"trace[74520970] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:681; }","duration":"481.074799ms","start":"2026-05-05T09:45:09.192754Z","end":"2026-05-05T09:45:09.673829Z","steps":["trace[74520970] 'read index received' (duration: 209.806064ms)","trace[74520970] 'applied index is now lower than readState.Index' (duration: 271.267505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:09.674057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.296055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5mn6x\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-05-05T09:45:09.674107Z","caller":"traceutil/trace.go:171","msg":"trace[333176354] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5mn6x; range_end:; response_count:1; response_revision:662; }","duration":"481.389187ms","start":"2026-05-05T09:45:09.192704Z","end":"2026-05-05T09:45:09.674093Z","steps":["trace[333176354] 'agreement among raft nodes before linearized reading' (duration: 481.197262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:09.674151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.192675Z","time spent":"481.465539ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17269,"request content":"key:\"/registry/pods/kube-system/cilium-5mn6x\" "} {"level":"info","ts":"2026-05-05T09:45:09.6742Z","caller":"traceutil/trace.go:171","msg":"trace[849767577] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"667.345913ms","start":"2026-05-05T09:45:09.006819Z","end":"2026-05-05T09:45:09.674165Z","steps":["trace[849767577] 'process raft request' (duration: 550.204214ms)","trace[849767577] 'compare' (duration: 116.249735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:09.674402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.00679Z","time spent":"667.536978ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:45:09.674424Z","caller":"traceutil/trace.go:171","msg":"trace[1097684408] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"664.742733ms","start":"2026-05-05T09:45:09.009665Z","end":"2026-05-05T09:45:09.674408Z","steps":["trace[1097684408] 'process raft request' (duration: 664.049765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:09.674516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.009657Z","time spent":"664.804035ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:09.674749Z","caller":"traceutil/trace.go:171","msg":"trace[1735511879] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"666.011188ms","start":"2026-05-05T09:45:09.008726Z","end":"2026-05-05T09:45:09.674737Z","steps":["trace[1735511879] 'process raft request' (duration: 664.898659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:09.674811Z","caller":"traceutil/trace.go:171","msg":"trace[961586970] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"487.585663ms","start":"2026-05-05T09:45:09.187211Z","end":"2026-05-05T09:45:09.674797Z","steps":["trace[961586970] 'process raft request' (duration: 486.562056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:09.674868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.008703Z","time spent":"666.073249ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:09.674939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:09.187116Z","time spent":"487.775927ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:09.898689Z","caller":"traceutil/trace.go:171","msg":"trace[1082688326] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"129.015495ms","start":"2026-05-05T09:45:09.769643Z","end":"2026-05-05T09:45:09.898658Z","steps":["trace[1082688326] 'process raft request' (duration: 31.180242ms)","trace[1082688326] 'compare' (duration: 97.644858ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:09.899141Z","caller":"traceutil/trace.go:171","msg":"trace[2037469901] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"127.280738ms","start":"2026-05-05T09:45:09.771838Z","end":"2026-05-05T09:45:09.899119Z","steps":["trace[2037469901] 'process raft request' (duration: 127.242487ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:09.899167Z","caller":"traceutil/trace.go:171","msg":"trace[1872699518] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"128.838181ms","start":"2026-05-05T09:45:09.770308Z","end":"2026-05-05T09:45:09.899146Z","steps":["trace[1872699518] 'process raft request' (duration: 128.605885ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:09.899247Z","caller":"traceutil/trace.go:171","msg":"trace[984052274] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"127.531895ms","start":"2026-05-05T09:45:09.771702Z","end":"2026-05-05T09:45:09.899234Z","steps":["trace[984052274] 'process raft request' (duration: 127.338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:13.658097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.474001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-05T09:45:13.658208Z","caller":"traceutil/trace.go:171","msg":"trace[2131680750] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"245.639365ms","start":"2026-05-05T09:45:13.412533Z","end":"2026-05-05T09:45:13.658173Z","steps":["trace[2131680750] 'range keys from in-memory index tree' (duration: 245.229965ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:16.116884Z","caller":"traceutil/trace.go:171","msg":"trace[122983425] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"132.295032ms","start":"2026-05-05T09:45:15.984564Z","end":"2026-05-05T09:45:16.116859Z","steps":["trace[122983425] 'process raft request' (duration: 132.085387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.180939Z","caller":"traceutil/trace.go:171","msg":"trace[1623028846] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"127.657003ms","start":"2026-05-05T09:45:17.053244Z","end":"2026-05-05T09:45:17.180901Z","steps":["trace[1623028846] 'process raft request' (duration: 127.418736ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.223002Z","caller":"traceutil/trace.go:171","msg":"trace[541774103] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:788; }","duration":"121.455359ms","start":"2026-05-05T09:45:17.101483Z","end":"2026-05-05T09:45:17.222938Z","steps":["trace[541774103] 'read index received' (duration: 79.185991ms)","trace[541774103] 'applied index is now lower than readState.Index' (duration: 42.267938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:17.223135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.644264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-05-05T09:45:17.223168Z","caller":"traceutil/trace.go:171","msg":"trace[1273408770] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:763; }","duration":"121.750966ms","start":"2026-05-05T09:45:17.101406Z","end":"2026-05-05T09:45:17.223157Z","steps":["trace[1273408770] 'agreement among raft nodes before linearized reading' (duration: 121.670284ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.223184Z","caller":"traceutil/trace.go:171","msg":"trace[180520035] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"143.28371ms","start":"2026-05-05T09:45:17.079831Z","end":"2026-05-05T09:45:17.223114Z","steps":["trace[180520035] 'process raft request' (duration: 142.879549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.367124Z","caller":"traceutil/trace.go:171","msg":"trace[1363599956] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"132.302236ms","start":"2026-05-05T09:45:17.234787Z","end":"2026-05-05T09:45:17.367089Z","steps":["trace[1363599956] 'read index received' (duration: 72.841164ms)","trace[1363599956] 'applied index is now lower than readState.Index' (duration: 59.459542ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:17.367169Z","caller":"traceutil/trace.go:171","msg":"trace[886524835] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"134.823948ms","start":"2026-05-05T09:45:17.232308Z","end":"2026-05-05T09:45:17.367132Z","steps":["trace[886524835] 'process raft request' (duration: 75.401847ms)","trace[886524835] 'compare' (duration: 59.065693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:17.367291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.47477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"info","ts":"2026-05-05T09:45:17.367331Z","caller":"traceutil/trace.go:171","msg":"trace[1411716501] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:764; }","duration":"132.565703ms","start":"2026-05-05T09:45:17.234753Z","end":"2026-05-05T09:45:17.367319Z","steps":["trace[1411716501] 'agreement among raft nodes before linearized reading' (duration: 132.44669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.493097Z","caller":"traceutil/trace.go:171","msg":"trace[1467017292] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"102.158351ms","start":"2026-05-05T09:45:17.390903Z","end":"2026-05-05T09:45:17.493062Z","steps":["trace[1467017292] 'read index received' (duration: 77.882709ms)","trace[1467017292] 'applied index is now lower than readState.Index' (duration: 24.273672ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:17.493214Z","caller":"traceutil/trace.go:171","msg":"trace[1452657281] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"108.369984ms","start":"2026-05-05T09:45:17.384801Z","end":"2026-05-05T09:45:17.493171Z","steps":["trace[1452657281] 'process raft request' (duration: 84.084433ms)","trace[1452657281] 'compare' (duration: 24.014125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:17.493319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.340565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:45:17.493371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.857312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:17.493423Z","caller":"traceutil/trace.go:171","msg":"trace[445932752] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:765; }","duration":"101.948635ms","start":"2026-05-05T09:45:17.391459Z","end":"2026-05-05T09:45:17.493408Z","steps":["trace[445932752] 'agreement among raft nodes before linearized reading' (duration: 101.832382ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:17.493424Z","caller":"traceutil/trace.go:171","msg":"trace[1862354579] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:765; }","duration":"102.54157ms","start":"2026-05-05T09:45:17.390862Z","end":"2026-05-05T09:45:17.493403Z","steps":["trace[1862354579] 'agreement among raft nodes before linearized reading' (duration: 102.313424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:17.493877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.935239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:17.493937Z","caller":"traceutil/trace.go:171","msg":"trace[2097361892] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:765; }","duration":"102.999341ms","start":"2026-05-05T09:45:17.390903Z","end":"2026-05-05T09:45:17.493903Z","steps":["trace[2097361892] 'agreement among raft nodes before linearized reading' (duration: 102.341775ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.051202Z","caller":"traceutil/trace.go:171","msg":"trace[1455005355] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"103.260637ms","start":"2026-05-05T09:45:17.947916Z","end":"2026-05-05T09:45:18.051177Z","steps":["trace[1455005355] 'process raft request' (duration: 100.350746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.075992Z","caller":"traceutil/trace.go:171","msg":"trace[18201513] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"127.753358ms","start":"2026-05-05T09:45:17.948212Z","end":"2026-05-05T09:45:18.075966Z","steps":["trace[18201513] 'process raft request' (duration: 127.464571ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.076068Z","caller":"traceutil/trace.go:171","msg":"trace[1071554985] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"127.371378ms","start":"2026-05-05T09:45:17.948545Z","end":"2026-05-05T09:45:18.075916Z","steps":["trace[1071554985] 'process raft request' (duration: 127.283716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.076242Z","caller":"traceutil/trace.go:171","msg":"trace[1773557568] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"127.017009ms","start":"2026-05-05T09:45:17.949197Z","end":"2026-05-05T09:45:18.076214Z","steps":["trace[1773557568] 'process raft request' (duration: 126.681711ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.631131Z","caller":"traceutil/trace.go:171","msg":"trace[1019598728] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"125.541502ms","start":"2026-05-05T09:45:18.505569Z","end":"2026-05-05T09:45:18.63111Z","steps":["trace[1019598728] 'process raft request' (duration: 125.423649ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:18.803371Z","caller":"traceutil/trace.go:171","msg":"trace[1558392855] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"164.106878ms","start":"2026-05-05T09:45:18.639232Z","end":"2026-05-05T09:45:18.803339Z","steps":["trace[1558392855] 'process raft request' (duration: 109.742813ms)","trace[1558392855] 'compare' (duration: 54.228522ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:18.838027Z","caller":"traceutil/trace.go:171","msg":"trace[1431449111] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"195.103758ms","start":"2026-05-05T09:45:18.642905Z","end":"2026-05-05T09:45:18.838009Z","steps":["trace[1431449111] 'process raft request' (duration: 195.020606ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:19.318993Z","caller":"traceutil/trace.go:171","msg":"trace[2143484721] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"128.231969ms","start":"2026-05-05T09:45:19.190734Z","end":"2026-05-05T09:45:19.318966Z","steps":["trace[2143484721] 'process raft request' (duration: 128.031054ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:22.488534Z","caller":"traceutil/trace.go:171","msg":"trace[1332405810] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"397.026121ms","start":"2026-05-05T09:45:22.091461Z","end":"2026-05-05T09:45:22.488488Z","steps":["trace[1332405810] 'read index received' (duration: 396.853437ms)","trace[1332405810] 'applied index is now lower than readState.Index' (duration: 171.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:22.48859Z","caller":"traceutil/trace.go:171","msg":"trace[772497069] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"399.54623ms","start":"2026-05-05T09:45:22.089025Z","end":"2026-05-05T09:45:22.488571Z","steps":["trace[772497069] 'process raft request' (duration: 399.338955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:22.488717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:22.088999Z","time spent":"399.639961ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:22.488726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.811174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T09:45:22.488786Z","caller":"traceutil/trace.go:171","msg":"trace[976960256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"396.928378ms","start":"2026-05-05T09:45:22.091847Z","end":"2026-05-05T09:45:22.488775Z","steps":["trace[976960256] 'agreement among raft nodes before linearized reading' (duration: 396.764094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:22.488828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:22.091834Z","time spent":"396.977188ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:45:22.488942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.4485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T09:45:22.488963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.176242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T09:45:22.489104Z","caller":"traceutil/trace.go:171","msg":"trace[633334832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"394.346286ms","start":"2026-05-05T09:45:22.094739Z","end":"2026-05-05T09:45:22.489086Z","steps":["trace[633334832] 'agreement among raft nodes before linearized reading' (duration: 393.966617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:22.489038Z","caller":"traceutil/trace.go:171","msg":"trace[820005581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"397.581514ms","start":"2026-05-05T09:45:22.091434Z","end":"2026-05-05T09:45:22.489016Z","steps":["trace[820005581] 'agreement among raft nodes before linearized reading' (duration: 397.170445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:22.489195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:22.094729Z","time spent":"394.453069ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:45:22.489331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:22.091424Z","time spent":"397.795149ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:45:24.848564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.039323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:24.848656Z","caller":"traceutil/trace.go:171","msg":"trace[122682164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"120.177276ms","start":"2026-05-05T09:45:24.728458Z","end":"2026-05-05T09:45:24.848636Z","steps":["trace[122682164] 'range keys from in-memory index tree' (duration: 119.991212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:24.848976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.245578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T09:45:24.848974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.012455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T09:45:24.849019Z","caller":"traceutil/trace.go:171","msg":"trace[2020010938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"123.300309ms","start":"2026-05-05T09:45:24.72571Z","end":"2026-05-05T09:45:24.84901Z","steps":["trace[2020010938] 'range keys from in-memory index tree' (duration: 123.130935ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:24.849039Z","caller":"traceutil/trace.go:171","msg":"trace[966655000] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:800; }","duration":"121.090607ms","start":"2026-05-05T09:45:24.727931Z","end":"2026-05-05T09:45:24.849022Z","steps":["trace[966655000] 'range keys from in-memory index tree' (duration: 120.839711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:24.849065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.276778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T09:45:24.849146Z","caller":"traceutil/trace.go:171","msg":"trace[1671647587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"123.38207ms","start":"2026-05-05T09:45:24.725743Z","end":"2026-05-05T09:45:24.849125Z","steps":["trace[1671647587] 'range keys from in-memory index tree' (duration: 123.120205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:24.849465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.888022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T09:45:24.849494Z","caller":"traceutil/trace.go:171","msg":"trace[1454349138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"123.949034ms","start":"2026-05-05T09:45:24.725535Z","end":"2026-05-05T09:45:24.849484Z","steps":["trace[1454349138] 'range keys from in-memory index tree' (duration: 123.656777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:24.878619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.916867ms","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-05T09:45:24.878711Z","caller":"traceutil/trace.go:171","msg":"trace[1996132326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"153.028129ms","start":"2026-05-05T09:45:24.725661Z","end":"2026-05-05T09:45:24.878689Z","steps":["trace[1996132326] 'range keys from in-memory index tree' (duration: 123.379891ms)","trace[1996132326] 'range keys from bolt db' (duration: 29.490775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:27.361686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033149544377563113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:45:27.598365Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229219813s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T09:45:27.598814Z","caller":"traceutil/trace.go:171","msg":"trace[1054034444] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.229800547s","start":"2026-05-05T09:45:26.368983Z","end":"2026-05-05T09:45:27.598784Z","steps":["trace[1054034444] 'process raft request' (duration: 1.229638803s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.598998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.368951Z","time spent":"1.229964101s","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:45:27.606123Z","caller":"traceutil/trace.go:171","msg":"trace[1541142254] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"745.174568ms","start":"2026-05-05T09:45:26.860931Z","end":"2026-05-05T09:45:27.606105Z","steps":["trace[1541142254] 'read index received' (duration: 737.690748ms)","trace[1541142254] 'applied index is now lower than readState.Index' (duration: 7.47466ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:27.606206Z","caller":"traceutil/trace.go:171","msg":"trace[982577299] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"370.194061ms","start":"2026-05-05T09:45:27.236001Z","end":"2026-05-05T09:45:27.606195Z","steps":["trace[982577299] 'process raft request' (duration: 370.016347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.606272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:27.235971Z","time spent":"370.257192ms","remote":"127.0.0.1:48320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:45:27.606445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.121228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T09:45:27.606522Z","caller":"traceutil/trace.go:171","msg":"trace[824429828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"742.243471ms","start":"2026-05-05T09:45:26.864259Z","end":"2026-05-05T09:45:27.606502Z","steps":["trace[824429828] 'agreement among raft nodes before linearized reading' (duration: 742.083827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.606596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.864233Z","time spent":"742.345732ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:45:27.606919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.964715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T09:45:27.606987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.839574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:45:27.606995Z","caller":"traceutil/trace.go:171","msg":"trace[711984682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"746.117568ms","start":"2026-05-05T09:45:26.860862Z","end":"2026-05-05T09:45:27.606979Z","steps":["trace[711984682] 'agreement among raft nodes before linearized reading' (duration: 745.624137ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:27.607025Z","caller":"traceutil/trace.go:171","msg":"trace[212191930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"714.906076ms","start":"2026-05-05T09:45:26.892108Z","end":"2026-05-05T09:45:27.607014Z","steps":["trace[212191930] 'agreement among raft nodes before linearized reading' (duration: 714.505637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.60692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.104044ms","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-05T09:45:27.607063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.892097Z","time spent":"714.959487ms","remote":"127.0.0.1:48408","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-05T09:45:27.607088Z","caller":"traceutil/trace.go:171","msg":"trace[1584945872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"162.34961ms","start":"2026-05-05T09:45:27.444719Z","end":"2026-05-05T09:45:27.607069Z","steps":["trace[1584945872] 'agreement among raft nodes before linearized reading' (duration: 161.790418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.607038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.860833Z","time spent":"746.1975ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:45:27.607232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.31427ms","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-05T09:45:27.607315Z","caller":"traceutil/trace.go:171","msg":"trace[1043325223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"724.481554ms","start":"2026-05-05T09:45:26.882816Z","end":"2026-05-05T09:45:27.607298Z","steps":["trace[1043325223] 'agreement among raft nodes before linearized reading' (duration: 724.185978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.607392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.882803Z","time spent":"724.548186ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:45:27.607858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.537391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:27.608137Z","caller":"traceutil/trace.go:171","msg":"trace[1052283474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"682.119646ms","start":"2026-05-05T09:45:26.92599Z","end":"2026-05-05T09:45:27.60811Z","steps":["trace[1052283474] 'agreement among raft nodes before linearized reading' (duration: 680.486349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:27.608333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:26.925978Z","time spent":"682.332842ms","remote":"127.0.0.1:48160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:45:37.927831Z","caller":"traceutil/trace.go:171","msg":"trace[245032843] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"221.789048ms","start":"2026-05-05T09:45:37.706027Z","end":"2026-05-05T09:45:37.927816Z","steps":["trace[245032843] 'process raft request' (duration: 221.656555ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:37.931726Z","caller":"traceutil/trace.go:171","msg":"trace[1707805305] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:972; }","duration":"145.480326ms","start":"2026-05-05T09:45:37.786214Z","end":"2026-05-05T09:45:37.931694Z","steps":["trace[1707805305] 'read index received' (duration: 141.575644ms)","trace[1707805305] 'applied index is now lower than readState.Index' (duration: 3.903202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:37.931939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.68354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:37.931991Z","caller":"traceutil/trace.go:171","msg":"trace[1732429020] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:942; }","duration":"145.794082ms","start":"2026-05-05T09:45:37.78618Z","end":"2026-05-05T09:45:37.931974Z","steps":["trace[1732429020] 'agreement among raft nodes before linearized reading' (duration: 145.67965ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:37.931878Z","caller":"traceutil/trace.go:171","msg":"trace[1575813093] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"225.372694ms","start":"2026-05-05T09:45:37.706466Z","end":"2026-05-05T09:45:37.931839Z","steps":["trace[1575813093] 'process raft request' (duration: 225.058517ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:39.481108Z","caller":"traceutil/trace.go:171","msg":"trace[2043385018] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"106.556679ms","start":"2026-05-05T09:45:39.374522Z","end":"2026-05-05T09:45:39.481079Z","steps":["trace[2043385018] 'process raft request' (duration: 106.408406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:10.303877Z","caller":"traceutil/trace.go:171","msg":"trace[2043368073] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"175.135767ms","start":"2026-05-05T09:46:10.1287Z","end":"2026-05-05T09:46:10.303836Z","steps":["trace[2043368073] 'process raft request' (duration: 109.293153ms)","trace[2043368073] 'compare' (duration: 65.662611ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:26.569414Z","caller":"traceutil/trace.go:171","msg":"trace[1328365132] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"138.526705ms","start":"2026-05-05T09:46:26.430868Z","end":"2026-05-05T09:46:26.569394Z","steps":["trace[1328365132] 'process raft request' (duration: 138.414983ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:45.33681Z","caller":"traceutil/trace.go:171","msg":"trace[1700221868] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"202.883231ms","start":"2026-05-05T09:47:45.133896Z","end":"2026-05-05T09:47:45.33678Z","steps":["trace[1700221868] 'read index received' (duration: 202.81038ms)","trace[1700221868] 'applied index is now lower than readState.Index' (duration: 71.401µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:47:45.33691Z","caller":"traceutil/trace.go:171","msg":"trace[934179725] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"330.268865ms","start":"2026-05-05T09:47:45.006609Z","end":"2026-05-05T09:47:45.336878Z","steps":["trace[934179725] 'process raft request' (duration: 329.978332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:47:45.337044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.122264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:47:45.337257Z","caller":"traceutil/trace.go:171","msg":"trace[1719078054] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1439; }","duration":"203.426787ms","start":"2026-05-05T09:47:45.133819Z","end":"2026-05-05T09:47:45.337245Z","steps":["trace[1719078054] 'agreement among raft nodes before linearized reading' (duration: 203.138944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:47:45.337595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:47:45.006571Z","time spent":"330.559118ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:47:45.456281Z","caller":"traceutil/trace.go:171","msg":"trace[565041098] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"254.148001ms","start":"2026-05-05T09:47:45.202089Z","end":"2026-05-05T09:47:45.456237Z","steps":["trace[565041098] 'process raft request' (duration: 252.224799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:50.766286Z","caller":"traceutil/trace.go:171","msg":"trace[50956083] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"162.834413ms","start":"2026-05-05T09:47:50.603406Z","end":"2026-05-05T09:47:50.76624Z","steps":["trace[50956083] 'process raft request' (duration: 72.959428ms)","trace[50956083] 'compare' (duration: 89.588582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:47:51.139454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.565552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:47:51.139539Z","caller":"traceutil/trace.go:171","msg":"trace[2013763524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1462; }","duration":"214.893336ms","start":"2026-05-05T09:47:50.924625Z","end":"2026-05-05T09:47:51.139519Z","steps":["trace[2013763524] 'range keys from in-memory index tree' (duration: 214.432791ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:48:05.592491Z","caller":"traceutil/trace.go:171","msg":"trace[61320305] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"124.667075ms","start":"2026-05-05T09:48:05.467782Z","end":"2026-05-05T09:48:05.592449Z","steps":["trace[61320305] 'process raft request' (duration: 124.346842ms)"],"step_count":1}