{"level":"warn","ts":"2026-04-25T15:59:18.610133Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-25T15:59:18.610224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-25T15:59:18.61029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-25T15:59:18.610298Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-25T15:59:18.610315Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-25T15:59:18.611033Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-25T15:59:18.611103Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-25T15:59:18.615533Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.217644ms"} {"level":"info","ts":"2026-04-25T15:59:18.627499Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-25T15:59:18.627573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-25T15:59:18.627597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-25T15:59:18.627609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-25T15:59:18.627616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-25T15:59:18.627656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-25T15:59:18.635313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-25T15:59:18.636626Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-25T15:59:18.637727Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-25T15:59:18.639864Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-25T15:59:18.640803Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-25T15:59:18.650163Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T15:59:18.651Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T15:59:18.651064Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T15:59:18.651612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-25T15:59:18.651786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-25T15:59:18.652344Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-25T15:59:18.652509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-25T15:59:18.652565Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-25T15:59:18.652638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-25T15:59:18.65268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-25T15:59:19.428726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-25T15:59:19.428778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-25T15:59:19.428794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-25T15:59:19.428807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-25T15:59:19.428813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-25T15:59:19.428822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-25T15:59:19.42883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-25T15:59:19.430827Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:19.432095Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T15:59:19.432093Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-25T15:59:19.432353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T15:59:19.432495Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-25T15:59:19.432836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-25T15:59:19.432533Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:19.433025Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:19.433073Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:19.435711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-25T15:59:19.43633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-25T15:59:22.203123Z","caller":"traceutil/trace.go:171","msg":"trace[1081280981] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"103.91682ms","start":"2026-04-25T15:59:22.099175Z","end":"2026-04-25T15:59:22.203092Z","steps":["trace[1081280981] 'process raft request' (duration: 92.12514ms)","trace[1081280981] 'compare' (duration: 11.663187ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:22.740112Z","caller":"traceutil/trace.go:171","msg":"trace[60527884] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"173.405713ms","start":"2026-04-25T15:59:22.56667Z","end":"2026-04-25T15:59:22.740076Z","steps":["trace[60527884] 'process raft request' (duration: 150.17579ms)","trace[60527884] 'compare' (duration: 22.897224ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:23.075845Z","caller":"traceutil/trace.go:171","msg":"trace[564321777] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"101.755377ms","start":"2026-04-25T15:59:22.974056Z","end":"2026-04-25T15:59:23.075811Z","steps":["trace[564321777] 'process raft request' (duration: 25.461518ms)","trace[564321777] 'compare' (duration: 76.028592ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:23.356682Z","caller":"traceutil/trace.go:171","msg":"trace[951757519] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"142.267115ms","start":"2026-04-25T15:59:23.214383Z","end":"2026-04-25T15:59:23.35665Z","steps":["trace[951757519] 'process raft request' (duration: 122.768155ms)","trace[951757519] 'compare' (duration: 19.360467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:23.704143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.439925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-25T15:59:23.704387Z","caller":"traceutil/trace.go:171","msg":"trace[1671423074] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"156.329621ms","start":"2026-04-25T15:59:23.548023Z","end":"2026-04-25T15:59:23.704352Z","steps":["trace[1671423074] 'process raft request' (duration: 156.252869ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:23.704447Z","caller":"traceutil/trace.go:171","msg":"trace[1210902376] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"166.948243ms","start":"2026-04-25T15:59:23.53743Z","end":"2026-04-25T15:59:23.704379Z","steps":["trace[1210902376] 'process raft request' (duration: 54.635996ms)","trace[1210902376] 'compare' (duration: 111.27185ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:29.530833Z","caller":"traceutil/trace.go:171","msg":"trace[1081841475] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"118.464389ms","start":"2026-04-25T15:59:29.412317Z","end":"2026-04-25T15:59:29.530781Z","steps":["trace[1081841475] 'process raft request' (duration: 54.848022ms)","trace[1081841475] 'compare' (duration: 63.394912ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:29.530786Z","caller":"traceutil/trace.go:171","msg":"trace[996894469] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:364; }","duration":"117.734461ms","start":"2026-04-25T15:59:29.413022Z","end":"2026-04-25T15:59:29.530757Z","steps":["trace[996894469] 'read index received' (duration: 54.146614ms)","trace[996894469] 'applied index is now lower than readState.Index' (duration: 63.586456ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:29.530848Z","caller":"traceutil/trace.go:171","msg":"trace[283650197] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"117.992357ms","start":"2026-04-25T15:59:29.412838Z","end":"2026-04-25T15:59:29.53083Z","steps":["trace[283650197] 'process raft request' (duration: 117.874944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:29.53105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.996217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-04-25T15:59:29.53109Z","caller":"traceutil/trace.go:171","msg":"trace[154290484] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:353; }","duration":"118.085539ms","start":"2026-04-25T15:59:29.412995Z","end":"2026-04-25T15:59:29.53108Z","steps":["trace[154290484] 'agreement among raft nodes before linearized reading' (duration: 117.980217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:29.531465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.225003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-04-25T15:59:29.531503Z","caller":"traceutil/trace.go:171","msg":"trace[959846109] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:353; }","duration":"118.277354ms","start":"2026-04-25T15:59:29.413204Z","end":"2026-04-25T15:59:29.531481Z","steps":["trace[959846109] 'agreement among raft nodes before linearized reading' (duration: 118.235343ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:30.912103Z","caller":"traceutil/trace.go:171","msg":"trace[1354882903] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"105.404948ms","start":"2026-04-25T15:59:30.806672Z","end":"2026-04-25T15:59:30.912076Z","steps":["trace[1354882903] 'process raft request' (duration: 65.127695ms)","trace[1354882903] 'compare' (duration: 39.910834ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:31.088326Z","caller":"traceutil/trace.go:171","msg":"trace[1808970042] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"134.827162ms","start":"2026-04-25T15:59:30.953466Z","end":"2026-04-25T15:59:31.088294Z","steps":["trace[1808970042] 'process raft request' (duration: 134.624817ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:31.558132Z","caller":"traceutil/trace.go:171","msg":"trace[180714865] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"142.469259ms","start":"2026-04-25T15:59:31.415627Z","end":"2026-04-25T15:59:31.558096Z","steps":["trace[180714865] 'process raft request' (duration: 108.80807ms)","trace[180714865] 'compare' (duration: 33.500425ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:31.904301Z","caller":"traceutil/trace.go:171","msg":"trace[926070723] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"111.580629ms","start":"2026-04-25T15:59:31.792685Z","end":"2026-04-25T15:59:31.904266Z","steps":["trace[926070723] 'process raft request' (duration: 111.383724ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:32.142905Z","caller":"traceutil/trace.go:171","msg":"trace[1446065498] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"118.737235ms","start":"2026-04-25T15:59:32.024135Z","end":"2026-04-25T15:59:32.142872Z","steps":["trace[1446065498] 'read index received' (duration: 52.853682ms)","trace[1446065498] 'applied index is now lower than readState.Index' (duration: 65.882063ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:32.143034Z","caller":"traceutil/trace.go:171","msg":"trace[602341926] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"205.904462ms","start":"2026-04-25T15:59:31.937083Z","end":"2026-04-25T15:59:32.142988Z","steps":["trace[602341926] 'process raft request' (duration: 139.875586ms)","trace[602341926] 'compare' (duration: 65.77952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:32.143081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.913069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-04-25T15:59:32.143129Z","caller":"traceutil/trace.go:171","msg":"trace[974508140] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:406; }","duration":"119.022142ms","start":"2026-04-25T15:59:32.024092Z","end":"2026-04-25T15:59:32.143114Z","steps":["trace[974508140] 'agreement among raft nodes before linearized reading' (duration: 118.910819ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:38.868743Z","caller":"traceutil/trace.go:171","msg":"trace[773170389] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"160.677558ms","start":"2026-04-25T15:59:38.70804Z","end":"2026-04-25T15:59:38.868717Z","steps":["trace[773170389] 'process raft request' (duration: 160.545674ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.300392Z","caller":"traceutil/trace.go:171","msg":"trace[1315031528] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"421.671226ms","start":"2026-04-25T15:59:47.878698Z","end":"2026-04-25T15:59:48.300369Z","steps":["trace[1315031528] 'process raft request' (duration: 421.546463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.300539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.878683Z","time spent":"421.78851ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.31404Z","caller":"traceutil/trace.go:171","msg":"trace[605513087] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"416.347175ms","start":"2026-04-25T15:59:47.897679Z","end":"2026-04-25T15:59:48.314026Z","steps":["trace[605513087] 'process raft request' (duration: 416.208201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.897663Z","time spent":"416.419837ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T15:59:48.314193Z","caller":"traceutil/trace.go:171","msg":"trace[1623437335] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:526; }","duration":"238.433649ms","start":"2026-04-25T15:59:48.075748Z","end":"2026-04-25T15:59:48.314181Z","steps":["trace[1623437335] 'read index received' (duration: 224.524316ms)","trace[1623437335] 'applied index is now lower than readState.Index' (duration: 13.908623ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:48.314219Z","caller":"traceutil/trace.go:171","msg":"trace[241053433] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"428.688699ms","start":"2026-04-25T15:59:47.885526Z","end":"2026-04-25T15:59:48.314215Z","steps":["trace[241053433] 'process raft request' (duration: 428.271469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.885512Z","time spent":"428.716839ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T15:59:48.314247Z","caller":"traceutil/trace.go:171","msg":"trace[92820291] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"416.507709ms","start":"2026-04-25T15:59:47.897733Z","end":"2026-04-25T15:59:48.314241Z","steps":["trace[92820291] 'process raft request' (duration: 416.180901ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.314288Z","caller":"traceutil/trace.go:171","msg":"trace[2029652239] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"422.850935ms","start":"2026-04-25T15:59:47.891434Z","end":"2026-04-25T15:59:48.314285Z","steps":["trace[2029652239] 'process raft request' (duration: 422.425714ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.314308Z","caller":"traceutil/trace.go:171","msg":"trace[1184486336] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"413.480893ms","start":"2026-04-25T15:59:47.900822Z","end":"2026-04-25T15:59:48.314303Z","steps":["trace[1184486336] 'process raft request' (duration: 413.119354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.900816Z","time spent":"413.510634ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.314388Z","caller":"traceutil/trace.go:171","msg":"trace[1678415857] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"365.239592ms","start":"2026-04-25T15:59:47.949143Z","end":"2026-04-25T15:59:48.314383Z","steps":["trace[1678415857] 'process raft request' (duration: 364.925545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.949122Z","time spent":"365.282494ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.3145Z","caller":"traceutil/trace.go:171","msg":"trace[456090273] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"409.081215ms","start":"2026-04-25T15:59:47.905415Z","end":"2026-04-25T15:59:48.314497Z","steps":["trace[456090273] 'process raft request' (duration: 408.550012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.90539Z","time spent":"409.125155ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.31457Z","caller":"traceutil/trace.go:171","msg":"trace[254116232] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"398.803181ms","start":"2026-04-25T15:59:47.915763Z","end":"2026-04-25T15:59:48.314566Z","steps":["trace[254116232] 'process raft request' (duration: 398.234767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.915749Z","time spent":"398.834161ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.314637Z","caller":"traceutil/trace.go:171","msg":"trace[390590975] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"367.348313ms","start":"2026-04-25T15:59:47.947284Z","end":"2026-04-25T15:59:48.314632Z","steps":["trace[390590975] 'process raft request' (duration: 366.750279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.947268Z","time spent":"367.381325ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T15:59:48.314424Z","caller":"traceutil/trace.go:171","msg":"trace[692981742] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"243.442953ms","start":"2026-04-25T15:59:48.070976Z","end":"2026-04-25T15:59:48.314419Z","steps":["trace[692981742] 'process raft request' (duration: 243.167446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.891418Z","time spent":"422.884595ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T15:59:48.314791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.035514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T15:59:48.314857Z","caller":"traceutil/trace.go:171","msg":"trace[1355614143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"239.128446ms","start":"2026-04-25T15:59:48.075719Z","end":"2026-04-25T15:59:48.314847Z","steps":["trace[1355614143] 'agreement among raft nodes before linearized reading' (duration: 238.718237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.897728Z","time spent":"416.532609ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T15:59:48.314202Z","caller":"traceutil/trace.go:171","msg":"trace[1207392958] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"435.16115ms","start":"2026-04-25T15:59:47.879036Z","end":"2026-04-25T15:59:48.314197Z","steps":["trace[1207392958] 'process raft request' (duration: 434.670388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.314945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:47.879004Z","time spent":"435.930719ms","remote":"127.0.0.1:34128","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-04-25T15:59:48.545996Z","caller":"traceutil/trace.go:171","msg":"trace[691664727] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"240.184412ms","start":"2026-04-25T15:59:48.305776Z","end":"2026-04-25T15:59:48.545961Z","steps":["trace[691664727] 'process raft request' (duration: 162.969755ms)","trace[691664727] 'compare' (duration: 76.981701ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:48.546061Z","caller":"traceutil/trace.go:171","msg":"trace[1536247712] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:538; }","duration":"231.817927ms","start":"2026-04-25T15:59:48.314224Z","end":"2026-04-25T15:59:48.546042Z","steps":["trace[1536247712] 'read index received' (duration: 154.572869ms)","trace[1536247712] 'applied index is now lower than readState.Index' (duration: 77.244068ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:48.546088Z","caller":"traceutil/trace.go:171","msg":"trace[1270631407] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"237.679921ms","start":"2026-04-25T15:59:48.30838Z","end":"2026-04-25T15:59:48.546059Z","steps":["trace[1270631407] 'process raft request' (duration: 237.62443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.546147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.700912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T15:59:48.546176Z","caller":"traceutil/trace.go:171","msg":"trace[518095148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"337.769524ms","start":"2026-04-25T15:59:48.208397Z","end":"2026-04-25T15:59:48.546167Z","steps":["trace[518095148] 'agreement among raft nodes before linearized reading' (duration: 337.705452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.546204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:48.208383Z","time spent":"337.814575ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T15:59:48.546234Z","caller":"traceutil/trace.go:171","msg":"trace[523634037] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"238.972934ms","start":"2026-04-25T15:59:48.307229Z","end":"2026-04-25T15:59:48.546202Z","steps":["trace[523634037] 'process raft request' (duration: 238.644555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:48.546441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.976618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T15:59:48.546552Z","caller":"traceutil/trace.go:171","msg":"trace[758360290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"240.123192ms","start":"2026-04-25T15:59:48.306408Z","end":"2026-04-25T15:59:48.546531Z","steps":["trace[758360290] 'agreement among raft nodes before linearized reading' (duration: 239.849825ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.547127Z","caller":"traceutil/trace.go:171","msg":"trace[794639808] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"220.712252ms","start":"2026-04-25T15:59:48.3264Z","end":"2026-04-25T15:59:48.547112Z","steps":["trace[794639808] 'process raft request' (duration: 220.491277ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.547342Z","caller":"traceutil/trace.go:171","msg":"trace[361649335] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"215.661607ms","start":"2026-04-25T15:59:48.331671Z","end":"2026-04-25T15:59:48.547333Z","steps":["trace[361649335] 'process raft request' (duration: 215.408811ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.547491Z","caller":"traceutil/trace.go:171","msg":"trace[614316363] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"215.810941ms","start":"2026-04-25T15:59:48.331671Z","end":"2026-04-25T15:59:48.547482Z","steps":["trace[614316363] 'process raft request' (duration: 215.36452ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.547585Z","caller":"traceutil/trace.go:171","msg":"trace[797523206] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"169.449725ms","start":"2026-04-25T15:59:48.378118Z","end":"2026-04-25T15:59:48.547567Z","steps":["trace[797523206] 'process raft request' (duration: 169.404974ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.548415Z","caller":"traceutil/trace.go:171","msg":"trace[112514047] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"211.949225ms","start":"2026-04-25T15:59:48.336446Z","end":"2026-04-25T15:59:48.548395Z","steps":["trace[112514047] 'process raft request' (duration: 210.717835ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.548422Z","caller":"traceutil/trace.go:171","msg":"trace[1313424621] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"215.165994ms","start":"2026-04-25T15:59:48.333237Z","end":"2026-04-25T15:59:48.548403Z","steps":["trace[1313424621] 'process raft request' (duration: 213.879053ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.548857Z","caller":"traceutil/trace.go:171","msg":"trace[874116685] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"207.050074ms","start":"2026-04-25T15:59:48.341788Z","end":"2026-04-25T15:59:48.548838Z","steps":["trace[874116685] 'process raft request' (duration: 205.592777ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.548973Z","caller":"traceutil/trace.go:171","msg":"trace[1469676484] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"210.541772ms","start":"2026-04-25T15:59:48.338418Z","end":"2026-04-25T15:59:48.54896Z","steps":["trace[1469676484] 'process raft request' (duration: 208.84958ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.549074Z","caller":"traceutil/trace.go:171","msg":"trace[1484946723] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"207.189517ms","start":"2026-04-25T15:59:48.341872Z","end":"2026-04-25T15:59:48.549061Z","steps":["trace[1484946723] 'process raft request' (duration: 205.586597ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.817118Z","caller":"traceutil/trace.go:171","msg":"trace[1237775264] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"185.209784ms","start":"2026-04-25T15:59:48.631882Z","end":"2026-04-25T15:59:48.817092Z","steps":["trace[1237775264] 'process raft request' (duration: 182.577439ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.817266Z","caller":"traceutil/trace.go:171","msg":"trace[1326739208] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"180.98607ms","start":"2026-04-25T15:59:48.636257Z","end":"2026-04-25T15:59:48.817243Z","steps":["trace[1326739208] 'process raft request' (duration: 180.677943ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.817367Z","caller":"traceutil/trace.go:171","msg":"trace[1918650024] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"180.803956ms","start":"2026-04-25T15:59:48.636512Z","end":"2026-04-25T15:59:48.817316Z","steps":["trace[1918650024] 'process raft request' (duration: 180.473968ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.818138Z","caller":"traceutil/trace.go:171","msg":"trace[191444199] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"174.834779ms","start":"2026-04-25T15:59:48.643286Z","end":"2026-04-25T15:59:48.81812Z","steps":["trace[191444199] 'process raft request' (duration: 174.754278ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:48.818284Z","caller":"traceutil/trace.go:171","msg":"trace[340213670] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"178.809397ms","start":"2026-04-25T15:59:48.639463Z","end":"2026-04-25T15:59:48.818273Z","steps":["trace[340213670] 'process raft request' (duration: 178.448408ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.025899Z","caller":"traceutil/trace.go:171","msg":"trace[176020559] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"190.921736ms","start":"2026-04-25T15:59:48.834947Z","end":"2026-04-25T15:59:49.025869Z","steps":["trace[176020559] 'process raft request' (duration: 114.121468ms)","trace[176020559] 'compare' (duration: 76.609593ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:49.063917Z","caller":"traceutil/trace.go:171","msg":"trace[2109020381] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"153.179052ms","start":"2026-04-25T15:59:48.910717Z","end":"2026-04-25T15:59:49.063896Z","steps":["trace[2109020381] 'read index received' (duration: 38.437988ms)","trace[2109020381] 'applied index is now lower than readState.Index' (duration: 114.739814ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:49.06398Z","caller":"traceutil/trace.go:171","msg":"trace[500541855] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"209.216867ms","start":"2026-04-25T15:59:48.854742Z","end":"2026-04-25T15:59:49.063959Z","steps":["trace[500541855] 'process raft request' (duration: 208.875019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:49.064102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.371728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-25T15:59:49.064158Z","caller":"traceutil/trace.go:171","msg":"trace[1390528706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"153.479501ms","start":"2026-04-25T15:59:48.910665Z","end":"2026-04-25T15:59:49.064144Z","steps":["trace[1390528706] 'agreement among raft nodes before linearized reading' (duration: 153.352477ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.064276Z","caller":"traceutil/trace.go:171","msg":"trace[925835536] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"187.732828ms","start":"2026-04-25T15:59:48.87651Z","end":"2026-04-25T15:59:49.064243Z","steps":["trace[925835536] 'process raft request' (duration: 187.310327ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.295551Z","caller":"traceutil/trace.go:171","msg":"trace[1830408525] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"258.415783ms","start":"2026-04-25T15:59:49.037102Z","end":"2026-04-25T15:59:49.295517Z","steps":["trace[1830408525] 'process raft request' (duration: 222.633779ms)","trace[1830408525] 'compare' (duration: 35.542098ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:49.295774Z","caller":"traceutil/trace.go:171","msg":"trace[83983577] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"231.421677ms","start":"2026-04-25T15:59:49.063996Z","end":"2026-04-25T15:59:49.295418Z","steps":["trace[83983577] 'read index received' (duration: 195.692464ms)","trace[83983577] 'applied index is now lower than readState.Index' (duration: 35.727863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:49.295825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.166081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-25T15:59:49.295919Z","caller":"traceutil/trace.go:171","msg":"trace[880679671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"259.310525ms","start":"2026-04-25T15:59:49.036589Z","end":"2026-04-25T15:59:49.295899Z","steps":["trace[880679671] 'agreement among raft nodes before linearized reading' (duration: 259.10429ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.322809Z","caller":"traceutil/trace.go:171","msg":"trace[252108110] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"254.820595ms","start":"2026-04-25T15:59:49.067953Z","end":"2026-04-25T15:59:49.322773Z","steps":["trace[252108110] 'process raft request' (duration: 254.658721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:49.322841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.851237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T15:59:49.322891Z","caller":"traceutil/trace.go:171","msg":"trace[1526291751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"112.909009ms","start":"2026-04-25T15:59:49.20997Z","end":"2026-04-25T15:59:49.322879Z","steps":["trace[1526291751] 'agreement among raft nodes before linearized reading' (duration: 112.814476ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.322927Z","caller":"traceutil/trace.go:171","msg":"trace[2097611390] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"219.311338ms","start":"2026-04-25T15:59:49.103568Z","end":"2026-04-25T15:59:49.32288Z","steps":["trace[2097611390] 'process raft request' (duration: 219.139873ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.323064Z","caller":"traceutil/trace.go:171","msg":"trace[1377481881] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"186.530738ms","start":"2026-04-25T15:59:49.136527Z","end":"2026-04-25T15:59:49.323057Z","steps":["trace[1377481881] 'process raft request' (duration: 186.224191ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:49.988733Z","caller":"traceutil/trace.go:171","msg":"trace[845849117] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"231.069948ms","start":"2026-04-25T15:59:49.757636Z","end":"2026-04-25T15:59:49.988706Z","steps":["trace[845849117] 'process raft request' (duration: 230.839812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:50.27514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.927396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T15:59:50.275279Z","caller":"traceutil/trace.go:171","msg":"trace[1044840503] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"434.526904ms","start":"2026-04-25T15:59:49.84074Z","end":"2026-04-25T15:59:50.275267Z","steps":["trace[1044840503] 'process raft request' (duration: 434.475882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:50.275321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:49.840708Z","time spent":"434.595345ms","remote":"127.0.0.1:34128","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-04-25T15:59:50.275429Z","caller":"traceutil/trace.go:171","msg":"trace[58530197] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"493.10425ms","start":"2026-04-25T15:59:49.782291Z","end":"2026-04-25T15:59:50.275395Z","steps":["trace[58530197] 'process raft request' (duration: 314.770884ms)","trace[58530197] 'compare' (duration: 177.800233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:50.275597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:49.782275Z","time spent":"493.235344ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T15:59:50.282856Z","caller":"traceutil/trace.go:171","msg":"trace[1228297356] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"276.592663ms","start":"2026-04-25T15:59:50.006247Z","end":"2026-04-25T15:59:50.282839Z","steps":["trace[1228297356] 'process raft request' (duration: 276.5143ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:50.56315Z","caller":"traceutil/trace.go:171","msg":"trace[1938961474] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"260.918206ms","start":"2026-04-25T15:59:50.302215Z","end":"2026-04-25T15:59:50.563134Z","steps":["trace[1938961474] 'process raft request' (duration: 260.814603ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:50.574676Z","caller":"traceutil/trace.go:171","msg":"trace[772121470] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"118.872497ms","start":"2026-04-25T15:59:50.455787Z","end":"2026-04-25T15:59:50.574659Z","steps":["trace[772121470] 'process raft request' (duration: 118.812436ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:50.575038Z","caller":"traceutil/trace.go:171","msg":"trace[1968684848] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"124.709291ms","start":"2026-04-25T15:59:50.450304Z","end":"2026-04-25T15:59:50.575014Z","steps":["trace[1968684848] 'process raft request' (duration: 123.840538ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:52.875196Z","caller":"traceutil/trace.go:171","msg":"trace[571117286] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"235.843796ms","start":"2026-04-25T15:59:52.639329Z","end":"2026-04-25T15:59:52.875173Z","steps":["trace[571117286] 'read index received' (duration: 221.972033ms)","trace[571117286] 'applied index is now lower than readState.Index' (duration: 13.870463ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T15:59:52.875309Z","caller":"traceutil/trace.go:171","msg":"trace[1305329881] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"253.502292ms","start":"2026-04-25T15:59:52.621773Z","end":"2026-04-25T15:59:52.875275Z","steps":["trace[1305329881] 'process raft request' (duration: 239.494656ms)","trace[1305329881] 'compare' (duration: 13.75313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:52.875399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.04418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T15:59:52.875616Z","caller":"traceutil/trace.go:171","msg":"trace[875483464] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:598; }","duration":"236.273336ms","start":"2026-04-25T15:59:52.639312Z","end":"2026-04-25T15:59:52.875585Z","steps":["trace[875483464] 'agreement among raft nodes before linearized reading' (duration: 236.018989ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:54.397648Z","caller":"traceutil/trace.go:171","msg":"trace[482956724] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"157.417788ms","start":"2026-04-25T15:59:54.240205Z","end":"2026-04-25T15:59:54.397622Z","steps":["trace[482956724] 'process raft request' (duration: 156.786413ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:55.518676Z","caller":"traceutil/trace.go:171","msg":"trace[1438241903] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"171.683682ms","start":"2026-04-25T15:59:55.346969Z","end":"2026-04-25T15:59:55.518652Z","steps":["trace[1438241903] 'process raft request' (duration: 171.494746ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:55.565354Z","caller":"traceutil/trace.go:171","msg":"trace[1923453581] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"201.18129ms","start":"2026-04-25T15:59:55.364146Z","end":"2026-04-25T15:59:55.565327Z","steps":["trace[1923453581] 'read index received' (duration: 154.390454ms)","trace[1923453581] 'applied index is now lower than readState.Index' (duration: 46.789776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T15:59:55.565639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.476997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-25T15:59:55.565724Z","caller":"traceutil/trace.go:171","msg":"trace[1210648314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"201.578629ms","start":"2026-04-25T15:59:55.364129Z","end":"2026-04-25T15:59:55.565708Z","steps":["trace[1210648314] 'agreement among raft nodes before linearized reading' (duration: 201.346124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:55.565844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.535759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-25T15:59:55.565928Z","caller":"traceutil/trace.go:171","msg":"trace[1364825872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"129.655982ms","start":"2026-04-25T15:59:55.436258Z","end":"2026-04-25T15:59:55.565914Z","steps":["trace[1364825872] 'agreement among raft nodes before linearized reading' (duration: 129.345795ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:55.565446Z","caller":"traceutil/trace.go:171","msg":"trace[1585128313] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"219.347348ms","start":"2026-04-25T15:59:55.346063Z","end":"2026-04-25T15:59:55.565411Z","steps":["trace[1585128313] 'process raft request' (duration: 218.631851ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T15:59:56.94487Z","caller":"traceutil/trace.go:171","msg":"trace[1460222491] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"376.657084ms","start":"2026-04-25T15:59:56.568187Z","end":"2026-04-25T15:59:56.944844Z","steps":["trace[1460222491] 'process raft request' (duration: 376.302445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T15:59:56.945002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T15:59:56.56816Z","time spent":"376.784308ms","remote":"127.0.0.1:34292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T15:59:56.947188Z","caller":"traceutil/trace.go:171","msg":"trace[514055097] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"232.084992ms","start":"2026-04-25T15:59:56.715083Z","end":"2026-04-25T15:59:56.947168Z","steps":["trace[514055097] 'process raft request' (duration: 231.760464ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:00:23.484388Z","caller":"traceutil/trace.go:171","msg":"trace[657601676] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"376.419118ms","start":"2026-04-25T16:00:23.107945Z","end":"2026-04-25T16:00:23.484364Z","steps":["trace[657601676] 'process raft request' (duration: 376.269885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:00:23.484533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:00:23.107934Z","time spent":"376.528751ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:00:23.491802Z","caller":"traceutil/trace.go:171","msg":"trace[1814593695] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"281.536505ms","start":"2026-04-25T16:00:23.210233Z","end":"2026-04-25T16:00:23.491769Z","steps":["trace[1814593695] 'read index received' (duration: 274.593904ms)","trace[1814593695] 'applied index is now lower than readState.Index' (duration: 6.941331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:00:23.491918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.666569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:00:23.491949Z","caller":"traceutil/trace.go:171","msg":"trace[201328714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"281.749011ms","start":"2026-04-25T16:00:23.210191Z","end":"2026-04-25T16:00:23.49194Z","steps":["trace[201328714] 'agreement among raft nodes before linearized reading' (duration: 281.681939ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:00:23.492113Z","caller":"traceutil/trace.go:171","msg":"trace[1937669079] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"381.837352ms","start":"2026-04-25T16:00:23.110259Z","end":"2026-04-25T16:00:23.492096Z","steps":["trace[1937669079] 'process raft request' (duration: 381.310869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:00:23.492221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:00:23.11025Z","time spent":"381.905724ms","remote":"127.0.0.1:34232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:03:18.695205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.763672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:03:18.695311Z","caller":"traceutil/trace.go:171","msg":"trace[495237413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1489; }","duration":"102.943087ms","start":"2026-04-25T16:03:18.592351Z","end":"2026-04-25T16:03:18.695294Z","steps":["trace[495237413] 'count revisions from in-memory index tree' (duration: 102.644149ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:03:18.807113Z","caller":"traceutil/trace.go:171","msg":"trace[1371731918] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"107.893633ms","start":"2026-04-25T16:03:18.699198Z","end":"2026-04-25T16:03:18.807092Z","steps":["trace[1371731918] 'process raft request' (duration: 107.387149ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:03:19.124863Z","caller":"traceutil/trace.go:171","msg":"trace[835680845] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"115.5253ms","start":"2026-04-25T16:03:19.009305Z","end":"2026-04-25T16:03:19.12483Z","steps":["trace[835680845] 'process raft request' (duration: 59.209245ms)","trace[835680845] 'compare' (duration: 56.152871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:03:36.929236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.09184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T16:03:36.929322Z","caller":"traceutil/trace.go:171","msg":"trace[2138062502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1548; }","duration":"294.212833ms","start":"2026-04-25T16:03:36.635089Z","end":"2026-04-25T16:03:36.929302Z","steps":["trace[2138062502] 'range keys from in-memory index tree' (duration: 293.914195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:36.929335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.917491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:03:36.92942Z","caller":"traceutil/trace.go:171","msg":"trace[232812327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1548; }","duration":"720.068175ms","start":"2026-04-25T16:03:36.20933Z","end":"2026-04-25T16:03:36.929398Z","steps":["trace[232812327] 'range keys from in-memory index tree' (duration: 719.785807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:36.929459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:36.209306Z","time spent":"720.145177ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T16:03:37.439002Z","caller":"traceutil/trace.go:171","msg":"trace[449385856] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"505.673368ms","start":"2026-04-25T16:03:36.93303Z","end":"2026-04-25T16:03:37.438704Z","steps":["trace[449385856] 'process raft request' (duration: 505.476703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:37.439141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:36.933016Z","time spent":"506.054589ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:03:38.141628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.604001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:03:38.141849Z","caller":"traceutil/trace.go:171","msg":"trace[148991286] linearizableReadLoop","detail":"{readStateIndex:1619; appliedIndex:1617; }","duration":"1.200042823s","start":"2026-04-25T16:03:36.941785Z","end":"2026-04-25T16:03:38.141828Z","steps":["trace[148991286] 'read index received' (duration: 496.754376ms)","trace[148991286] 'applied index is now lower than readState.Index' (duration: 703.287137ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:03:38.141956Z","caller":"traceutil/trace.go:171","msg":"trace[1524496804] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"1.149256839s","start":"2026-04-25T16:03:36.992686Z","end":"2026-04-25T16:03:38.141943Z","steps":["trace[1524496804] 'process raft request' (duration: 1.149062333s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:38.142018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:36.992671Z","time spent":"1.149311151s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:03:38.142045Z","caller":"traceutil/trace.go:171","msg":"trace[709878638] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"1.208297738s","start":"2026-04-25T16:03:36.9337Z","end":"2026-04-25T16:03:38.141997Z","steps":["trace[709878638] 'process raft request' (duration: 623.214524ms)","trace[709878638] 'compare' (duration: 584.158348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:03:38.142241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:36.933684Z","time spent":"1.208448813s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:03:38.142447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.684886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-25T16:03:38.142448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.748837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-25T16:03:38.142513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.94386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:03:38.142527Z","caller":"traceutil/trace.go:171","msg":"trace[114206055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1551; }","duration":"938.81499ms","start":"2026-04-25T16:03:37.20369Z","end":"2026-04-25T16:03:38.142505Z","steps":["trace[114206055] 'agreement among raft nodes before linearized reading' (duration: 938.591624ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:03:38.142528Z","caller":"traceutil/trace.go:171","msg":"trace[1636122713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1551; }","duration":"488.878311ms","start":"2026-04-25T16:03:37.653632Z","end":"2026-04-25T16:03:38.14251Z","steps":["trace[1636122713] 'agreement among raft nodes before linearized reading' (duration: 488.684416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:38.142521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200709822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-25T16:03:38.14256Z","caller":"traceutil/trace.go:171","msg":"trace[1610114351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1551; }","duration":"933.013351ms","start":"2026-04-25T16:03:37.209533Z","end":"2026-04-25T16:03:38.142546Z","steps":["trace[1610114351] 'agreement among raft nodes before linearized reading' (duration: 932.941019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:38.142576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:37.203667Z","time spent":"938.900641ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:03:38.142576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:37.653612Z","time spent":"488.954883ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:03:38.142597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:37.209522Z","time spent":"933.067733ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T16:03:38.142599Z","caller":"traceutil/trace.go:171","msg":"trace[1615000909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1551; }","duration":"1.200842775s","start":"2026-04-25T16:03:36.941736Z","end":"2026-04-25T16:03:38.142579Z","steps":["trace[1615000909] 'agreement among raft nodes before linearized reading' (duration: 1.200593488s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:03:38.142643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:03:36.941725Z","time spent":"1.200909587s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:04:00.711195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594897878,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-25T16:04:00.83709Z","caller":"traceutil/trace.go:171","msg":"trace[441112562] linearizableReadLoop","detail":"{readStateIndex:1696; appliedIndex:1695; }","duration":"626.467582ms","start":"2026-04-25T16:04:00.210597Z","end":"2026-04-25T16:04:00.837065Z","steps":["trace[441112562] 'read index received' (duration: 626.348869ms)","trace[441112562] 'applied index is now lower than readState.Index' (duration: 114.963µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:00.837257Z","caller":"traceutil/trace.go:171","msg":"trace[1894558090] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"626.946615ms","start":"2026-04-25T16:04:00.210298Z","end":"2026-04-25T16:04:00.837245Z","steps":["trace[1894558090] 'process raft request' (duration: 626.605086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.837445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.210282Z","time spent":"627.010706ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:00.837471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.396932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T16:04:00.837557Z","caller":"traceutil/trace.go:171","msg":"trace[2022598226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1622; }","duration":"607.500365ms","start":"2026-04-25T16:04:00.230037Z","end":"2026-04-25T16:04:00.837537Z","steps":["trace[2022598226] 'agreement among raft nodes before linearized reading' (duration: 607.294069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.837888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.230001Z","time spent":"607.594317ms","remote":"127.0.0.1:34310","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-04-25T16:04:00.837877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.64078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-25T16:04:00.837977Z","caller":"traceutil/trace.go:171","msg":"trace[503857475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1622; }","duration":"584.790795ms","start":"2026-04-25T16:04:00.253147Z","end":"2026-04-25T16:04:00.837938Z","steps":["trace[503857475] 'agreement among raft nodes before linearized reading' (duration: 584.527948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.838051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.25313Z","time spent":"584.901887ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:04:00.838096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.638541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-25T16:04:00.838138Z","caller":"traceutil/trace.go:171","msg":"trace[848271337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1622; }","duration":"591.705264ms","start":"2026-04-25T16:04:00.246421Z","end":"2026-04-25T16:04:00.838127Z","steps":["trace[848271337] 'agreement among raft nodes before linearized reading' (duration: 591.55653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.838171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.246407Z","time spent":"591.757805ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:00.838389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.3762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-25T16:04:00.838463Z","caller":"traceutil/trace.go:171","msg":"trace[660736194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1622; }","duration":"585.489934ms","start":"2026-04-25T16:04:00.252956Z","end":"2026-04-25T16:04:00.838446Z","steps":["trace[660736194] 'agreement among raft nodes before linearized reading' (duration: 584.921728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.838507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.252946Z","time spent":"585.552696ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:00.838663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.060096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:00.838721Z","caller":"traceutil/trace.go:171","msg":"trace[997057803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1622; }","duration":"628.158118ms","start":"2026-04-25T16:04:00.210551Z","end":"2026-04-25T16:04:00.838709Z","steps":["trace[997057803] 'agreement among raft nodes before linearized reading' (duration: 627.277474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:00.83875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:00.210533Z","time spent":"628.21098ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:02.709504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.519432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:02.709608Z","caller":"traceutil/trace.go:171","msg":"trace[1599575329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1627; }","duration":"499.230891ms","start":"2026-04-25T16:04:02.210353Z","end":"2026-04-25T16:04:02.709584Z","steps":["trace[1599575329] 'range keys from in-memory index tree' (duration: 498.392898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:02.709753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.210333Z","time spent":"499.400627ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:02.820542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594897889,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:04:03.249883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0137032s","expected-duration":"1s"} {"level":"info","ts":"2026-04-25T16:04:03.250555Z","caller":"traceutil/trace.go:171","msg":"trace[548844359] linearizableReadLoop","detail":"{readStateIndex:1702; appliedIndex:1701; }","duration":"930.095959ms","start":"2026-04-25T16:04:02.320437Z","end":"2026-04-25T16:04:03.250533Z","steps":["trace[548844359] 'read index received' (duration: 929.955926ms)","trace[548844359] 'applied index is now lower than readState.Index' (duration: 139.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:03.25065Z","caller":"traceutil/trace.go:171","msg":"trace[1192478006] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"1.014610615s","start":"2026-04-25T16:04:02.236004Z","end":"2026-04-25T16:04:03.250615Z","steps":["trace[1192478006] 'process raft request' (duration: 1.014388049s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.25071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.244774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:03.250747Z","caller":"traceutil/trace.go:171","msg":"trace[1818622276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1628; }","duration":"930.352267ms","start":"2026-04-25T16:04:02.320384Z","end":"2026-04-25T16:04:03.250736Z","steps":["trace[1818622276] 'agreement among raft nodes before linearized reading' (duration: 930.263004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.250792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.320362Z","time spent":"930.418828ms","remote":"127.0.0.1:48318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:04:03.250832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.235973Z","time spent":"1.014762149s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:03.267159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.835895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:03.267241Z","caller":"traceutil/trace.go:171","msg":"trace[550111620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1629; }","duration":"555.955489ms","start":"2026-04-25T16:04:02.711266Z","end":"2026-04-25T16:04:03.267222Z","steps":["trace[550111620] 'agreement among raft nodes before linearized reading' (duration: 555.831295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.267281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.711239Z","time spent":"556.034291ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:03.267454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.954073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-25T16:04:03.267504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.477627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T16:04:03.267529Z","caller":"traceutil/trace.go:171","msg":"trace[1447141545] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"423.695393ms","start":"2026-04-25T16:04:02.843808Z","end":"2026-04-25T16:04:03.267503Z","steps":["trace[1447141545] 'process raft request' (duration: 423.163438ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:03.267568Z","caller":"traceutil/trace.go:171","msg":"trace[1209664471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1629; }","duration":"375.560839ms","start":"2026-04-25T16:04:02.891994Z","end":"2026-04-25T16:04:03.267555Z","steps":["trace[1209664471] 'agreement among raft nodes before linearized reading' (duration: 375.364254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.267779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.891983Z","time spent":"375.785765ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-25T16:04:03.26757Z","caller":"traceutil/trace.go:171","msg":"trace[1723543653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1629; }","duration":"375.090467ms","start":"2026-04-25T16:04:02.892442Z","end":"2026-04-25T16:04:03.267533Z","steps":["trace[1723543653] 'agreement among raft nodes before linearized reading' (duration: 374.715126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.267816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.843781Z","time spent":"423.866527ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:03.26789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.892435Z","time spent":"375.445516ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:03.267894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.248049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-25T16:04:03.267942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.417713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-25T16:04:03.267982Z","caller":"traceutil/trace.go:171","msg":"trace[86372416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1629; }","duration":"376.505936ms","start":"2026-04-25T16:04:02.891465Z","end":"2026-04-25T16:04:03.267971Z","steps":["trace[86372416] 'agreement among raft nodes before linearized reading' (duration: 376.160206ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:03.267988Z","caller":"traceutil/trace.go:171","msg":"trace[1852240713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1629; }","duration":"376.719792ms","start":"2026-04-25T16:04:02.891258Z","end":"2026-04-25T16:04:03.267978Z","steps":["trace[1852240713] 'agreement among raft nodes before linearized reading' (duration: 375.984972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:03.268024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.891238Z","time spent":"376.778693ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:03.268021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:02.891454Z","time spent":"376.558957ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-25T16:04:03.912008Z","caller":"traceutil/trace.go:171","msg":"trace[1532615800] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"173.862091ms","start":"2026-04-25T16:04:03.738121Z","end":"2026-04-25T16:04:03.911983Z","steps":["trace[1532615800] 'process raft request' (duration: 173.701707ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:04.909493Z","caller":"traceutil/trace.go:171","msg":"trace[665670841] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"386.60772ms","start":"2026-04-25T16:04:04.522857Z","end":"2026-04-25T16:04:04.909465Z","steps":["trace[665670841] 'read index received' (duration: 386.397505ms)","trace[665670841] 'applied index is now lower than readState.Index' (duration: 208.585µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:04:04.909801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.919209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:04.909901Z","caller":"traceutil/trace.go:171","msg":"trace[1151229025] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1634; }","duration":"387.079734ms","start":"2026-04-25T16:04:04.522807Z","end":"2026-04-25T16:04:04.909886Z","steps":["trace[1151229025] 'agreement among raft nodes before linearized reading' (duration: 386.913489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:04.909992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:04.52279Z","time spent":"387.182436ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:04:04.910055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:04.464819Z","time spent":"445.232099ms","remote":"127.0.0.1:34098","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-25T16:04:05.38396Z","caller":"traceutil/trace.go:171","msg":"trace[1519549230] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"108.399216ms","start":"2026-04-25T16:04:05.275289Z","end":"2026-04-25T16:04:05.383689Z","steps":["trace[1519549230] 'process raft request' (duration: 108.208491ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:05.670535Z","caller":"traceutil/trace.go:171","msg":"trace[99630121] linearizableReadLoop","detail":"{readStateIndex:1715; appliedIndex:1711; }","duration":"272.87679ms","start":"2026-04-25T16:04:05.397635Z","end":"2026-04-25T16:04:05.670512Z","steps":["trace[99630121] 'read index received' (duration: 31.396815ms)","trace[99630121] 'applied index is now lower than readState.Index' (duration: 241.478615ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:05.670588Z","caller":"traceutil/trace.go:171","msg":"trace[1142430861] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"280.488168ms","start":"2026-04-25T16:04:05.390078Z","end":"2026-04-25T16:04:05.670566Z","steps":["trace[1142430861] 'process raft request' (duration: 280.400636ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:05.670588Z","caller":"traceutil/trace.go:171","msg":"trace[385890210] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"282.222875ms","start":"2026-04-25T16:04:05.388331Z","end":"2026-04-25T16:04:05.670553Z","steps":["trace[385890210] 'process raft request' (duration: 282.108072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:05.670656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.997234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:05.671078Z","caller":"traceutil/trace.go:171","msg":"trace[1502801941] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1640; }","duration":"273.445606ms","start":"2026-04-25T16:04:05.397607Z","end":"2026-04-25T16:04:05.671052Z","steps":["trace[1502801941] 'agreement among raft nodes before linearized reading' (duration: 272.994953ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:05.670736Z","caller":"traceutil/trace.go:171","msg":"trace[1721886910] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"282.372569ms","start":"2026-04-25T16:04:05.388309Z","end":"2026-04-25T16:04:05.670682Z","steps":["trace[1721886910] 'process raft request' (duration: 282.00414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:06.317287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.528694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:06.317442Z","caller":"traceutil/trace.go:171","msg":"trace[712813560] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1640; }","duration":"509.708618ms","start":"2026-04-25T16:04:05.807713Z","end":"2026-04-25T16:04:06.317422Z","steps":["trace[712813560] 'count revisions from in-memory index tree' (duration: 509.39975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:06.31754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:05.807568Z","time spent":"509.955515ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:04:06.317885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.901313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-25T16:04:06.31799Z","caller":"traceutil/trace.go:171","msg":"trace[1009566014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1640; }","duration":"400.052528ms","start":"2026-04-25T16:04:05.917915Z","end":"2026-04-25T16:04:06.317968Z","steps":["trace[1009566014] 'range keys from in-memory index tree' (duration: 398.877025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:06.318056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:05.917887Z","time spent":"400.155171ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-25T16:04:06.317848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.711669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:06.318236Z","caller":"traceutil/trace.go:171","msg":"trace[728221499] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1640; }","duration":"495.176612ms","start":"2026-04-25T16:04:05.823043Z","end":"2026-04-25T16:04:06.318219Z","steps":["trace[728221499] 'count revisions from in-memory index tree' (duration: 494.585385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:06.318293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:05.823002Z","time spent":"495.272474ms","remote":"127.0.0.1:34522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":115,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:04:06.717823Z","caller":"traceutil/trace.go:171","msg":"trace[1565687009] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"261.333415ms","start":"2026-04-25T16:04:06.456447Z","end":"2026-04-25T16:04:06.71778Z","steps":["trace[1565687009] 'process raft request' (duration: 212.993878ms)","trace[1565687009] 'compare' (duration: 48.222994ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:19.066471Z","caller":"traceutil/trace.go:171","msg":"trace[299745296] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"100.965023ms","start":"2026-04-25T16:04:18.96548Z","end":"2026-04-25T16:04:19.066445Z","steps":["trace[299745296] 'process raft request' (duration: 100.825089ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.343815Z","caller":"traceutil/trace.go:171","msg":"trace[2035567240] linearizableReadLoop","detail":"{readStateIndex:1776; appliedIndex:1775; }","duration":"133.317744ms","start":"2026-04-25T16:04:19.210461Z","end":"2026-04-25T16:04:19.343779Z","steps":["trace[2035567240] 'read index received' (duration: 133.190441ms)","trace[2035567240] 'applied index is now lower than readState.Index' (duration: 126.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:19.343853Z","caller":"traceutil/trace.go:171","msg":"trace[1521443632] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"157.003261ms","start":"2026-04-25T16:04:19.186815Z","end":"2026-04-25T16:04:19.343819Z","steps":["trace[1521443632] 'process raft request' (duration: 156.784036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:19.343979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.5007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:19.344096Z","caller":"traceutil/trace.go:171","msg":"trace[2110552559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1698; }","duration":"133.674345ms","start":"2026-04-25T16:04:19.210411Z","end":"2026-04-25T16:04:19.344085Z","steps":["trace[2110552559] 'agreement among raft nodes before linearized reading' (duration: 133.472059ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.401627Z","caller":"traceutil/trace.go:171","msg":"trace[1896377974] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"188.801168ms","start":"2026-04-25T16:04:19.212785Z","end":"2026-04-25T16:04:19.401586Z","steps":["trace[1896377974] 'process raft request' (duration: 188.584913ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.401795Z","caller":"traceutil/trace.go:171","msg":"trace[970228429] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"187.841342ms","start":"2026-04-25T16:04:19.213836Z","end":"2026-04-25T16:04:19.401678Z","steps":["trace[970228429] 'process raft request' (duration: 187.634156ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.539424Z","caller":"traceutil/trace.go:171","msg":"trace[1697349189] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"126.481479ms","start":"2026-04-25T16:04:19.412921Z","end":"2026-04-25T16:04:19.539403Z","steps":["trace[1697349189] 'process raft request' (duration: 126.123879ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.539572Z","caller":"traceutil/trace.go:171","msg":"trace[114311010] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"125.251985ms","start":"2026-04-25T16:04:19.414311Z","end":"2026-04-25T16:04:19.539563Z","steps":["trace[114311010] 'process raft request' (duration: 125.003699ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.572096Z","caller":"traceutil/trace.go:171","msg":"trace[612632613] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"118.350247ms","start":"2026-04-25T16:04:19.453719Z","end":"2026-04-25T16:04:19.572069Z","steps":["trace[612632613] 'process raft request' (duration: 117.895225ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.736535Z","caller":"traceutil/trace.go:171","msg":"trace[1365409668] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"187.986676ms","start":"2026-04-25T16:04:19.548532Z","end":"2026-04-25T16:04:19.736518Z","steps":["trace[1365409668] 'process raft request' (duration: 187.950745ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.736562Z","caller":"traceutil/trace.go:171","msg":"trace[1369067902] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"190.244157ms","start":"2026-04-25T16:04:19.546301Z","end":"2026-04-25T16:04:19.736545Z","steps":["trace[1369067902] 'process raft request' (duration: 190.090972ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:19.741011Z","caller":"traceutil/trace.go:171","msg":"trace[1633447697] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"164.990859ms","start":"2026-04-25T16:04:19.575992Z","end":"2026-04-25T16:04:19.740983Z","steps":["trace[1633447697] 'process raft request' (duration: 164.795004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.248081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898139,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-25T16:04:22.300944Z","caller":"traceutil/trace.go:171","msg":"trace[1331122122] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1820; }","duration":"553.929964ms","start":"2026-04-25T16:04:21.746988Z","end":"2026-04-25T16:04:22.300918Z","steps":["trace[1331122122] 'read index received' (duration: 553.669757ms)","trace[1331122122] 'applied index is now lower than readState.Index' (duration: 258.667µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:22.30112Z","caller":"traceutil/trace.go:171","msg":"trace[415783926] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"721.463303ms","start":"2026-04-25T16:04:21.579642Z","end":"2026-04-25T16:04:22.301106Z","steps":["trace[415783926] 'process raft request' (duration: 721.108364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.301303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:21.579625Z","time spent":"721.532935ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:22.301412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.902366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-25T16:04:22.30148Z","caller":"traceutil/trace.go:171","msg":"trace[786113037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1741; }","duration":"542.011019ms","start":"2026-04-25T16:04:21.759451Z","end":"2026-04-25T16:04:22.301462Z","steps":["trace[786113037] 'agreement among raft nodes before linearized reading' (duration: 541.829554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.301519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:21.759434Z","time spent":"542.0771ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:22.30176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.770636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-25T16:04:22.301812Z","caller":"traceutil/trace.go:171","msg":"trace[43259776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1741; }","duration":"554.857299ms","start":"2026-04-25T16:04:21.74694Z","end":"2026-04-25T16:04:22.301798Z","steps":["trace[43259776] 'agreement among raft nodes before linearized reading' (duration: 554.708755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.301852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:21.74692Z","time spent":"554.921751ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:22.301941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.327952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-25T16:04:22.30201Z","caller":"traceutil/trace.go:171","msg":"trace[604474694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1741; }","duration":"533.441535ms","start":"2026-04-25T16:04:21.768553Z","end":"2026-04-25T16:04:22.301994Z","steps":["trace[604474694] 'agreement among raft nodes before linearized reading' (duration: 533.278221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.302003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.331808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:04:22.302058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:21.768532Z","time spent":"533.514318ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-25T16:04:22.302069Z","caller":"traceutil/trace.go:171","msg":"trace[845272506] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1741; }","duration":"110.435961ms","start":"2026-04-25T16:04:22.191613Z","end":"2026-04-25T16:04:22.302049Z","steps":["trace[845272506] 'agreement among raft nodes before linearized reading' (duration: 110.343468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.302157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.635945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-25T16:04:22.302219Z","caller":"traceutil/trace.go:171","msg":"trace[1919574156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1741; }","duration":"542.736278ms","start":"2026-04-25T16:04:21.759469Z","end":"2026-04-25T16:04:22.302205Z","steps":["trace[1919574156] 'agreement among raft nodes before linearized reading' (duration: 542.600114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:22.302273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:21.759445Z","time spent":"542.8126ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:24.812066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:04:25.312477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:04:25.649969Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342305691s","expected-duration":"1s"} {"level":"info","ts":"2026-04-25T16:04:25.650371Z","caller":"traceutil/trace.go:171","msg":"trace[724972680] linearizableReadLoop","detail":"{readStateIndex:1828; appliedIndex:1827; }","duration":"1.3392487s","start":"2026-04-25T16:04:24.311095Z","end":"2026-04-25T16:04:25.650344Z","steps":["trace[724972680] 'read index received' (duration: 1.338990402s)","trace[724972680] 'applied index is now lower than readState.Index' (duration: 256.918µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:25.650464Z","caller":"traceutil/trace.go:171","msg":"trace[26456476] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"1.342878927s","start":"2026-04-25T16:04:24.307573Z","end":"2026-04-25T16:04:25.650452Z","steps":["trace[26456476] 'process raft request' (duration: 1.342595399s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.650695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.237957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:25.650795Z","caller":"traceutil/trace.go:171","msg":"trace[482863908] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1748; }","duration":"296.387421ms","start":"2026-04-25T16:04:25.354388Z","end":"2026-04-25T16:04:25.650776Z","steps":["trace[482863908] 'agreement among raft nodes before linearized reading' (duration: 296.208956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.650815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330974292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-25T16:04:25.650798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.781822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:25.65087Z","caller":"traceutil/trace.go:171","msg":"trace[734808553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1748; }","duration":"1.331026153s","start":"2026-04-25T16:04:24.319831Z","end":"2026-04-25T16:04:25.650858Z","steps":["trace[734808553] 'agreement among raft nodes before linearized reading' (duration: 1.330925241s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:25.650886Z","caller":"traceutil/trace.go:171","msg":"trace[1551674691] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1748; }","duration":"695.927307ms","start":"2026-04-25T16:04:24.954941Z","end":"2026-04-25T16:04:25.650869Z","steps":["trace[1551674691] 'agreement among raft nodes before linearized reading' (duration: 695.752502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.650907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.319787Z","time spent":"1.331112935s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:25.65094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.95492Z","time spent":"696.009798ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:04:25.650953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33196536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-25T16:04:25.650959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186276148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-25T16:04:25.651017Z","caller":"traceutil/trace.go:171","msg":"trace[1835249640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1748; }","duration":"1.332034172s","start":"2026-04-25T16:04:24.318959Z","end":"2026-04-25T16:04:25.650993Z","steps":["trace[1835249640] 'agreement among raft nodes before linearized reading' (duration: 1.331906569s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:25.651026Z","caller":"traceutil/trace.go:171","msg":"trace[1913533506] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1748; }","duration":"1.186376879s","start":"2026-04-25T16:04:24.464634Z","end":"2026-04-25T16:04:25.651011Z","steps":["trace[1913533506] 'agreement among raft nodes before linearized reading' (duration: 1.186174204s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.651084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.318953Z","time spent":"1.332119304s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:25.651084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.464618Z","time spent":"1.186454112s","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-04-25T16:04:25.650813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332806782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-25T16:04:25.651163Z","caller":"traceutil/trace.go:171","msg":"trace[1566587210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1748; }","duration":"1.333184692s","start":"2026-04-25T16:04:24.317967Z","end":"2026-04-25T16:04:25.651151Z","steps":["trace[1566587210] 'agreement among raft nodes before linearized reading' (duration: 1.332616427s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.650717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339625059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-25T16:04:25.651206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.317933Z","time spent":"1.333261894s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-25T16:04:25.651245Z","caller":"traceutil/trace.go:171","msg":"trace[1177919417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1748; }","duration":"1.340187234s","start":"2026-04-25T16:04:24.311042Z","end":"2026-04-25T16:04:25.651229Z","steps":["trace[1177919417] 'agreement among raft nodes before linearized reading' (duration: 1.339562268s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.651291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.311018Z","time spent":"1.340259057s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:04:25.65157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.307554Z","time spent":"1.34294471s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:25.651636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050102224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-25T16:04:25.651672Z","caller":"traceutil/trace.go:171","msg":"trace[360757893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1748; }","duration":"1.050173986s","start":"2026-04-25T16:04:24.601487Z","end":"2026-04-25T16:04:25.651661Z","steps":["trace[360757893] 'agreement among raft nodes before linearized reading' (duration: 1.049628581s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:25.651701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:24.601467Z","time spent":"1.050229457s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-25T16:04:26.359733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:04:26.50937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.652228Z","time spent":"857.138643ms","remote":"127.0.0.1:34098","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-25T16:04:26.512093Z","caller":"traceutil/trace.go:171","msg":"trace[701922749] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"856.32075ms","start":"2026-04-25T16:04:25.65575Z","end":"2026-04-25T16:04:26.512071Z","steps":["trace[701922749] 'process raft request' (duration: 856.233898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.512233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.65574Z","time spent":"856.420003ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:04:26.512233Z","caller":"traceutil/trace.go:171","msg":"trace[562713727] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"857.005339ms","start":"2026-04-25T16:04:25.655205Z","end":"2026-04-25T16:04:26.512211Z","steps":["trace[562713727] 'process raft request' (duration: 856.636039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.512367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.655184Z","time spent":"857.103051ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:04:26.512394Z","caller":"traceutil/trace.go:171","msg":"trace[495512084] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"855.692293ms","start":"2026-04-25T16:04:25.65668Z","end":"2026-04-25T16:04:26.512372Z","steps":["trace[495512084] 'process raft request' (duration: 855.556259ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:26.512438Z","caller":"traceutil/trace.go:171","msg":"trace[89485267] linearizableReadLoop","detail":"{readStateIndex:1834; appliedIndex:1828; }","duration":"653.601592ms","start":"2026-04-25T16:04:25.85882Z","end":"2026-04-25T16:04:26.512422Z","steps":["trace[89485267] 'read index received' (duration: 650.510908ms)","trace[89485267] 'applied index is now lower than readState.Index' (duration: 3.089704ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:26.512483Z","caller":"traceutil/trace.go:171","msg":"trace[179330265] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"855.60768ms","start":"2026-04-25T16:04:25.656865Z","end":"2026-04-25T16:04:26.512473Z","steps":["trace[179330265] 'process raft request' (duration: 855.457046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.512526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.656667Z","time spent":"855.788585ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:26.512531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.656844Z","time spent":"855.661402ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:26.512588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.753536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:26.512643Z","caller":"traceutil/trace.go:171","msg":"trace[1401530668] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1753; }","duration":"653.892159ms","start":"2026-04-25T16:04:25.858736Z","end":"2026-04-25T16:04:26.512628Z","steps":["trace[1401530668] 'agreement among raft nodes before linearized reading' (duration: 653.757866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.512678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.858713Z","time spent":"653.957072ms","remote":"127.0.0.1:34422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:04:26.512804Z","caller":"traceutil/trace.go:171","msg":"trace[918099472] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"856.815783ms","start":"2026-04-25T16:04:25.655959Z","end":"2026-04-25T16:04:26.512775Z","steps":["trace[918099472] 'process raft request' (duration: 856.078374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.513338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.65595Z","time spent":"856.965397ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:26.516354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.705232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:26.51641Z","caller":"traceutil/trace.go:171","msg":"trace[1877450429] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1754; }","duration":"599.764324ms","start":"2026-04-25T16:04:25.916632Z","end":"2026-04-25T16:04:26.516396Z","steps":["trace[1877450429] 'agreement among raft nodes before linearized reading' (duration: 599.659041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.516436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.18409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:04:26.516464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:25.916586Z","time spent":"599.869058ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:04:26.516488Z","caller":"traceutil/trace.go:171","msg":"trace[2048135657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1754; }","duration":"306.272292ms","start":"2026-04-25T16:04:26.210201Z","end":"2026-04-25T16:04:26.516474Z","steps":["trace[2048135657] 'agreement among raft nodes before linearized reading' (duration: 306.174629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.516524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:26.210181Z","time spent":"306.336024ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:26.516663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.57762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:26.516701Z","caller":"traceutil/trace.go:171","msg":"trace[2018109595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1754; }","duration":"405.663212ms","start":"2026-04-25T16:04:26.111028Z","end":"2026-04-25T16:04:26.516691Z","steps":["trace[2018109595] 'agreement among raft nodes before linearized reading' (duration: 405.60498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:26.516735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:26.111008Z","time spent":"405.719244ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:04:37.922212Z","caller":"traceutil/trace.go:171","msg":"trace[1638293529] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"233.612129ms","start":"2026-04-25T16:04:37.688564Z","end":"2026-04-25T16:04:37.922177Z","steps":["trace[1638293529] 'process raft request' (duration: 233.448135ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:38.744626Z","caller":"traceutil/trace.go:171","msg":"trace[1281030680] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"106.863753ms","start":"2026-04-25T16:04:38.637733Z","end":"2026-04-25T16:04:38.744596Z","steps":["trace[1281030680] 'process raft request' (duration: 106.762751ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:38.744658Z","caller":"traceutil/trace.go:171","msg":"trace[1452925798] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"107.957974ms","start":"2026-04-25T16:04:38.636668Z","end":"2026-04-25T16:04:38.744626Z","steps":["trace[1452925798] 'process raft request' (duration: 50.063715ms)","trace[1452925798] 'compare' (duration: 57.634332ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:38.744883Z","caller":"traceutil/trace.go:171","msg":"trace[2102136581] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"106.35467ms","start":"2026-04-25T16:04:38.638503Z","end":"2026-04-25T16:04:38.744858Z","steps":["trace[2102136581] 'process raft request' (duration: 106.231277ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:38.744906Z","caller":"traceutil/trace.go:171","msg":"trace[2027988536] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"106.611647ms","start":"2026-04-25T16:04:38.638272Z","end":"2026-04-25T16:04:38.744884Z","steps":["trace[2027988536] 'process raft request' (duration: 106.260168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:40.709845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898273,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:04:41.048381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.121412039s","expected-duration":"1s"} {"level":"info","ts":"2026-04-25T16:04:41.048623Z","caller":"traceutil/trace.go:171","msg":"trace[326156532] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"839.169682ms","start":"2026-04-25T16:04:40.209437Z","end":"2026-04-25T16:04:41.048606Z","steps":["trace[326156532] 'read index received' (duration: 839.028488ms)","trace[326156532] 'applied index is now lower than readState.Index' (duration: 140.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:41.04882Z","caller":"traceutil/trace.go:171","msg":"trace[1871125124] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"1.122006455s","start":"2026-04-25T16:04:39.926805Z","end":"2026-04-25T16:04:41.048812Z","steps":["trace[1871125124] 'process raft request' (duration: 1.121698886s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:41.048913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:39.926776Z","time spent":"1.122084397s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:41.049048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.624224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:41.049068Z","caller":"traceutil/trace.go:171","msg":"trace[294810147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1795; }","duration":"839.685786ms","start":"2026-04-25T16:04:40.209376Z","end":"2026-04-25T16:04:41.049062Z","steps":["trace[294810147] 'agreement among raft nodes before linearized reading' (duration: 839.652895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:41.049086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:40.209352Z","time spent":"839.729847ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:41.049261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.758378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-25T16:04:41.049285Z","caller":"traceutil/trace.go:171","msg":"trace[73785883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1795; }","duration":"411.83186ms","start":"2026-04-25T16:04:40.637447Z","end":"2026-04-25T16:04:41.049279Z","steps":["trace[73785883] 'agreement among raft nodes before linearized reading' (duration: 411.717487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:41.049304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:40.637412Z","time spent":"411.887062ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:41.049424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.558341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-25T16:04:41.049441Z","caller":"traceutil/trace.go:171","msg":"trace[1989306209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1795; }","duration":"298.611762ms","start":"2026-04-25T16:04:40.750824Z","end":"2026-04-25T16:04:41.049436Z","steps":["trace[1989306209] 'agreement among raft nodes before linearized reading' (duration: 298.561511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:41.049498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.647924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-25T16:04:41.049521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.739767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-25T16:04:41.049531Z","caller":"traceutil/trace.go:171","msg":"trace[1126034195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"298.693546ms","start":"2026-04-25T16:04:40.750829Z","end":"2026-04-25T16:04:41.049523Z","steps":["trace[1126034195] 'agreement among raft nodes before linearized reading' (duration: 298.615483ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:41.049536Z","caller":"traceutil/trace.go:171","msg":"trace[791726819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1795; }","duration":"298.781898ms","start":"2026-04-25T16:04:40.75075Z","end":"2026-04-25T16:04:41.049532Z","steps":["trace[791726819] 'agreement among raft nodes before linearized reading' (duration: 298.736297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:41.049561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.762237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-25T16:04:41.049606Z","caller":"traceutil/trace.go:171","msg":"trace[1128969616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"298.84579ms","start":"2026-04-25T16:04:40.75075Z","end":"2026-04-25T16:04:41.049596Z","steps":["trace[1128969616] 'agreement among raft nodes before linearized reading' (duration: 298.667814ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:47.557672Z","caller":"traceutil/trace.go:171","msg":"trace[629020682] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"347.465895ms","start":"2026-04-25T16:04:47.21019Z","end":"2026-04-25T16:04:47.557656Z","steps":["trace[629020682] 'read index received' (duration: 347.435914ms)","trace[629020682] 'applied index is now lower than readState.Index' (duration: 28.851µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:04:47.557768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.577948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:47.557766Z","caller":"traceutil/trace.go:171","msg":"trace[343021240] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"424.954128ms","start":"2026-04-25T16:04:47.132792Z","end":"2026-04-25T16:04:47.557746Z","steps":["trace[343021240] 'process raft request' (duration: 424.764173ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:47.557809Z","caller":"traceutil/trace.go:171","msg":"trace[500427840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"347.670111ms","start":"2026-04-25T16:04:47.210131Z","end":"2026-04-25T16:04:47.557801Z","steps":["trace[500427840] 'agreement among raft nodes before linearized reading' (duration: 347.603829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:47.557837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.210106Z","time spent":"347.723822ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:47.558039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.132766Z","time spent":"425.095692ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:48.058703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898384,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-25T16:04:48.277962Z","caller":"traceutil/trace.go:171","msg":"trace[2019091709] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"836.245203ms","start":"2026-04-25T16:04:47.441692Z","end":"2026-04-25T16:04:48.277938Z","steps":["trace[2019091709] 'process raft request' (duration: 836.079318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.278296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.441667Z","time spent":"836.369887ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:48.804491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.628582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:04:48.804604Z","caller":"traceutil/trace.go:171","msg":"trace[173354730] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1942; }","duration":"1.24686791s","start":"2026-04-25T16:04:47.55772Z","end":"2026-04-25T16:04:48.804588Z","steps":["trace[173354730] 'read index received' (duration: 720.146337ms)","trace[173354730] 'applied index is now lower than readState.Index' (duration: 526.719883ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:48.804698Z","caller":"traceutil/trace.go:171","msg":"trace[1265230351] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"1.11964457s","start":"2026-04-25T16:04:47.685039Z","end":"2026-04-25T16:04:48.804684Z","steps":["trace[1265230351] 'process raft request' (duration: 717.753941ms)","trace[1265230351] 'compare' (duration: 401.437697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:04:48.804768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.685018Z","time spent":"1.119721253s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T16:04:48.805206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561956482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-25T16:04:48.805253Z","caller":"traceutil/trace.go:171","msg":"trace[160309492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1859; }","duration":"1.562074525s","start":"2026-04-25T16:04:47.243167Z","end":"2026-04-25T16:04:48.805242Z","steps":["trace[160309492] 'agreement among raft nodes before linearized reading' (duration: 1.561824089s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.805286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.243146Z","time spent":"1.562130966s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-25T16:04:48.805379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246550521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:48.805469Z","caller":"traceutil/trace.go:171","msg":"trace[576900975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"1.246662254s","start":"2026-04-25T16:04:47.558786Z","end":"2026-04-25T16:04:48.805448Z","steps":["trace[576900975] 'agreement among raft nodes before linearized reading' (duration: 1.246546381s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.805532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.558772Z","time spent":"1.246747306s","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:04:48.805532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.035289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-25T16:04:48.805588Z","caller":"traceutil/trace.go:171","msg":"trace[742130378] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1859; }","duration":"224.114841ms","start":"2026-04-25T16:04:48.581459Z","end":"2026-04-25T16:04:48.805573Z","steps":["trace[742130378] 'agreement among raft nodes before linearized reading' (duration: 223.988448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.80536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563443533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-25T16:04:48.805791Z","caller":"traceutil/trace.go:171","msg":"trace[2034845348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"1.563934746s","start":"2026-04-25T16:04:47.241836Z","end":"2026-04-25T16:04:48.805771Z","steps":["trace[2034845348] 'agreement among raft nodes before linearized reading' (duration: 1.563326159s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.805849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.241809Z","time spent":"1.564026747s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:04:48.806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.756302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:48.806064Z","caller":"traceutil/trace.go:171","msg":"trace[612036500] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1859; }","duration":"647.859005ms","start":"2026-04-25T16:04:48.158191Z","end":"2026-04-25T16:04:48.80605Z","steps":["trace[612036500] 'agreement among raft nodes before linearized reading' (duration: 647.759272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.806109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:48.158173Z","time spent":"647.923307ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:04:48.806357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563567306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T16:04:48.80644Z","caller":"traceutil/trace.go:171","msg":"trace[1494613269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"1.564684275s","start":"2026-04-25T16:04:47.24174Z","end":"2026-04-25T16:04:48.806424Z","steps":["trace[1494613269] 'agreement among raft nodes before linearized reading' (duration: 1.563414521s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.806489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.241731Z","time spent":"1.564744006s","remote":"127.0.0.1:34310","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-04-25T16:04:48.806897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5663365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-25T16:04:48.806995Z","caller":"traceutil/trace.go:171","msg":"trace[691636662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1859; }","duration":"1.568066338s","start":"2026-04-25T16:04:47.238909Z","end":"2026-04-25T16:04:48.806976Z","steps":["trace[691636662] 'agreement among raft nodes before linearized reading' (duration: 1.566207917s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.807225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.238895Z","time spent":"1.568247143s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-25T16:04:48.807457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565993191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-25T16:04:48.807546Z","caller":"traceutil/trace.go:171","msg":"trace[142732327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"1.566123425s","start":"2026-04-25T16:04:47.241406Z","end":"2026-04-25T16:04:48.80753Z","steps":["trace[142732327] 'agreement among raft nodes before linearized reading' (duration: 1.563837162s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:48.808303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:47.241392Z","time spent":"1.566895306s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-25T16:04:49.012148Z","caller":"traceutil/trace.go:171","msg":"trace[811081317] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"201.021982ms","start":"2026-04-25T16:04:48.811097Z","end":"2026-04-25T16:04:49.012119Z","steps":["trace[811081317] 'process raft request' (duration: 200.59647ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:49.012549Z","caller":"traceutil/trace.go:171","msg":"trace[330580761] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"199.308415ms","start":"2026-04-25T16:04:48.813204Z","end":"2026-04-25T16:04:49.012512Z","steps":["trace[330580761] 'process raft request' (duration: 199.143171ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:49.012571Z","caller":"traceutil/trace.go:171","msg":"trace[1989907545] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"199.014187ms","start":"2026-04-25T16:04:48.813524Z","end":"2026-04-25T16:04:49.012538Z","steps":["trace[1989907545] 'process raft request' (duration: 198.932125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:49.012654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.46727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:49.012546Z","caller":"traceutil/trace.go:171","msg":"trace[2090295794] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1944; }","duration":"195.321026ms","start":"2026-04-25T16:04:48.817187Z","end":"2026-04-25T16:04:49.012508Z","steps":["trace[2090295794] 'read index received' (duration: 58.223057ms)","trace[2090295794] 'applied index is now lower than readState.Index' (duration: 137.096749ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:49.012711Z","caller":"traceutil/trace.go:171","msg":"trace[1110014472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"195.576833ms","start":"2026-04-25T16:04:48.81712Z","end":"2026-04-25T16:04:49.012696Z","steps":["trace[1110014472] 'agreement among raft nodes before linearized reading' (duration: 195.481521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:49.012899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.923914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-25T16:04:49.01298Z","caller":"traceutil/trace.go:171","msg":"trace[970447230] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1864; }","duration":"137.002306ms","start":"2026-04-25T16:04:48.875924Z","end":"2026-04-25T16:04:49.012926Z","steps":["trace[970447230] 'agreement among raft nodes before linearized reading' (duration: 136.859503ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:49.01286Z","caller":"traceutil/trace.go:171","msg":"trace[1334573444] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"200.345992ms","start":"2026-04-25T16:04:48.812135Z","end":"2026-04-25T16:04:49.012481Z","steps":["trace[1334573444] 'process raft request' (duration: 199.936871ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:49.808074Z","caller":"traceutil/trace.go:171","msg":"trace[458026989] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"361.305892ms","start":"2026-04-25T16:04:49.446745Z","end":"2026-04-25T16:04:49.808051Z","steps":["trace[458026989] 'process raft request' (duration: 361.174358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:49.808199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:49.446725Z","time spent":"361.416955ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T16:04:50.312246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.193456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-25T16:04:50.313231Z","caller":"traceutil/trace.go:171","msg":"trace[1276127414] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"496.012536ms","start":"2026-04-25T16:04:49.817198Z","end":"2026-04-25T16:04:50.31321Z","steps":["trace[1276127414] 'process raft request' (duration: 495.905143ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:50.313265Z","caller":"traceutil/trace.go:171","msg":"trace[1093563528] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1952; }","duration":"499.947702ms","start":"2026-04-25T16:04:49.813292Z","end":"2026-04-25T16:04:50.31324Z","steps":["trace[1093563528] 'read index received' (duration: 15.76771ms)","trace[1093563528] 'applied index is now lower than readState.Index' (duration: 484.177202ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:04:50.31325Z","caller":"traceutil/trace.go:171","msg":"trace[1315787715] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"750.975638ms","start":"2026-04-25T16:04:49.562246Z","end":"2026-04-25T16:04:50.313222Z","steps":["trace[1315787715] 'process raft request' (duration: 750.092694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.313341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:49.817155Z","time spent":"496.118918ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T16:04:50.313405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:49.562221Z","time spent":"751.099032ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:04:50.313492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.174088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-25T16:04:50.31357Z","caller":"traceutil/trace.go:171","msg":"trace[1762726300] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1869; }","duration":"500.301973ms","start":"2026-04-25T16:04:49.813245Z","end":"2026-04-25T16:04:50.313547Z","steps":["trace[1762726300] 'agreement among raft nodes before linearized reading' (duration: 500.069426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.313614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:49.813222Z","time spent":"500.383604ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-25T16:04:50.3404Z","caller":"traceutil/trace.go:171","msg":"trace[1143941211] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"476.919234ms","start":"2026-04-25T16:04:49.863447Z","end":"2026-04-25T16:04:50.340367Z","steps":["trace[1143941211] 'process raft request' (duration: 476.74991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.340563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:49.863423Z","time spent":"477.055758ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T16:04:50.340432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.89803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:04:50.340679Z","caller":"traceutil/trace.go:171","msg":"trace[523750948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"131.199788ms","start":"2026-04-25T16:04:50.20946Z","end":"2026-04-25T16:04:50.34066Z","steps":["trace[523750948] 'agreement among raft nodes before linearized reading' (duration: 130.869159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.756272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.008031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:04:50.756355Z","caller":"traceutil/trace.go:171","msg":"trace[1788971267] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"364.155509ms","start":"2026-04-25T16:04:50.392187Z","end":"2026-04-25T16:04:50.756342Z","steps":["trace[1788971267] 'read index received' (duration: 86.104757ms)","trace[1788971267] 'applied index is now lower than readState.Index' (duration: 278.049632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:04:50.756472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.030005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-25T16:04:50.756496Z","caller":"traceutil/trace.go:171","msg":"trace[153727311] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1872; }","duration":"387.088866ms","start":"2026-04-25T16:04:50.3694Z","end":"2026-04-25T16:04:50.756489Z","steps":["trace[153727311] 'agreement among raft nodes before linearized reading' (duration: 386.974723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.756559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:50.369384Z","time spent":"387.168018ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-25T16:04:50.756669Z","caller":"traceutil/trace.go:171","msg":"trace[412105924] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"413.407363ms","start":"2026-04-25T16:04:50.343221Z","end":"2026-04-25T16:04:50.756628Z","steps":["trace[412105924] 'process raft request' (duration: 134.987971ms)","trace[412105924] 'compare' (duration: 277.916568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:04:50.756843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:50.343204Z","time spent":"413.568577ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T16:04:50.756963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.268164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T16:04:50.75708Z","caller":"traceutil/trace.go:171","msg":"trace[250737351] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1872; }","duration":"371.560182ms","start":"2026-04-25T16:04:50.385497Z","end":"2026-04-25T16:04:50.757057Z","steps":["trace[250737351] 'agreement among raft nodes before linearized reading' (duration: 371.182092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:04:50.757307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:04:50.385467Z","time spent":"371.697686ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:04:50.916238Z","caller":"traceutil/trace.go:171","msg":"trace[53242440] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"154.326368ms","start":"2026-04-25T16:04:50.761888Z","end":"2026-04-25T16:04:50.916214Z","steps":["trace[53242440] 'process raft request' (duration: 149.112355ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:55.154557Z","caller":"traceutil/trace.go:171","msg":"trace[1075681953] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"104.854759ms","start":"2026-04-25T16:04:55.049675Z","end":"2026-04-25T16:04:55.15453Z","steps":["trace[1075681953] 'process raft request' (duration: 95.048571ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:55.154714Z","caller":"traceutil/trace.go:171","msg":"trace[1347570041] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"104.141761ms","start":"2026-04-25T16:04:55.05045Z","end":"2026-04-25T16:04:55.154592Z","steps":["trace[1347570041] 'process raft request' (duration: 104.058188ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:04:55.154723Z","caller":"traceutil/trace.go:171","msg":"trace[631587055] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"104.757406ms","start":"2026-04-25T16:04:55.049896Z","end":"2026-04-25T16:04:55.154653Z","steps":["trace[631587055] 'process raft request' (duration: 104.566761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:05.792519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.869023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-25T16:05:05.792948Z","caller":"traceutil/trace.go:171","msg":"trace[191100958] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1976; }","duration":"377.352454ms","start":"2026-04-25T16:05:05.415575Z","end":"2026-04-25T16:05:05.792928Z","steps":["trace[191100958] 'range keys from in-memory index tree' (duration: 376.614306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:05.79302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:05.415555Z","time spent":"377.448707ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-25T16:05:14.166441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.656464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:05:14.166522Z","caller":"traceutil/trace.go:171","msg":"trace[667799288] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"858.934051ms","start":"2026-04-25T16:05:13.307573Z","end":"2026-04-25T16:05:14.166508Z","steps":["trace[667799288] 'process raft request' (duration: 26.141383ms)","trace[667799288] 'compare' (duration: 832.544292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:05:14.166563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:13.307549Z","time spent":"858.995983ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:05:14.210763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199239594898733,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T16:05:14.644305Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302465404s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-25T16:05:15.53451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.957148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:05:15.535278Z","caller":"traceutil/trace.go:171","msg":"trace[67666504] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"2.224015966s","start":"2026-04-25T16:05:13.311228Z","end":"2026-04-25T16:05:15.535244Z","steps":["trace[67666504] 'process raft request' (duration: 1.333232966s)","trace[67666504] 'compare' (duration: 889.861816ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:05:15.535443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:13.311171Z","time spent":"2.22418407s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:05:15.535478Z","caller":"traceutil/trace.go:171","msg":"trace[750375308] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"1.129945724s","start":"2026-04-25T16:05:14.405495Z","end":"2026-04-25T16:05:15.53544Z","steps":["trace[750375308] 'process raft request' (duration: 1.129686547s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.535433Z","caller":"traceutil/trace.go:171","msg":"trace[1950127116] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2105; }","duration":"1.824878s","start":"2026-04-25T16:05:13.710531Z","end":"2026-04-25T16:05:15.535409Z","steps":["trace[1950127116] 'read index received' (duration: 934.047669ms)","trace[1950127116] 'applied index is now lower than readState.Index' (duration: 890.826871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:05:15.535555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825021334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:05:15.53561Z","caller":"traceutil/trace.go:171","msg":"trace[981584208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2018; }","duration":"1.825127417s","start":"2026-04-25T16:05:13.710467Z","end":"2026-04-25T16:05:15.535595Z","steps":["trace[981584208] 'agreement among raft nodes before linearized reading' (duration: 1.825007864s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:15.535673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:13.710447Z","time spent":"1.825211238s","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:05:15.535677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.40547Z","time spent":"1.130117709s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:05:15.535816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681054678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2813"} {"level":"warn","ts":"2026-04-25T16:05:15.535871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.415443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:05:15.535915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.481861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T16:05:15.535941Z","caller":"traceutil/trace.go:171","msg":"trace[2119968036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2018; }","duration":"197.515346ms","start":"2026-04-25T16:05:15.338405Z","end":"2026-04-25T16:05:15.53592Z","steps":["trace[2119968036] 'agreement among raft nodes before linearized reading' (duration: 197.413103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:15.535942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326950716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:05:15.535979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240112051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-25T16:05:15.536042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.275513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-25T16:05:15.53606Z","caller":"traceutil/trace.go:171","msg":"trace[1794598352] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2018; }","duration":"1.240240554s","start":"2026-04-25T16:05:14.295803Z","end":"2026-04-25T16:05:15.536043Z","steps":["trace[1794598352] 'agreement among raft nodes before linearized reading' (duration: 1.239979247s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.536071Z","caller":"traceutil/trace.go:171","msg":"trace[1217547532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2018; }","duration":"294.339205ms","start":"2026-04-25T16:05:15.241724Z","end":"2026-04-25T16:05:15.536063Z","steps":["trace[1217547532] 'agreement among raft nodes before linearized reading' (duration: 294.265533ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.535882Z","caller":"traceutil/trace.go:171","msg":"trace[320235784] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2018; }","duration":"1.681159901s","start":"2026-04-25T16:05:13.854706Z","end":"2026-04-25T16:05:15.535866Z","steps":["trace[320235784] 'agreement among raft nodes before linearized reading' (duration: 1.680971086s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:15.536104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.295782Z","time spent":"1.240313916s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-25T16:05:15.53613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451005976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-25T16:05:15.536139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:13.854675Z","time spent":"1.681449407s","remote":"127.0.0.1:34232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2835,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-25T16:05:15.535951Z","caller":"traceutil/trace.go:171","msg":"trace[1844023014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2018; }","duration":"239.541582ms","start":"2026-04-25T16:05:15.296398Z","end":"2026-04-25T16:05:15.535939Z","steps":["trace[1844023014] 'agreement among raft nodes before linearized reading' (duration: 239.45814ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.536184Z","caller":"traceutil/trace.go:171","msg":"trace[1682647071] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2018; }","duration":"1.451095138s","start":"2026-04-25T16:05:14.085076Z","end":"2026-04-25T16:05:15.536171Z","steps":["trace[1682647071] 'agreement among raft nodes before linearized reading' (duration: 1.450956735s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:15.535831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.726087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-25T16:05:15.536227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.085056Z","time spent":"1.45116074s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-25T16:05:15.536245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067678982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-25T16:05:15.536264Z","caller":"traceutil/trace.go:171","msg":"trace[765459659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2018; }","duration":"483.192889ms","start":"2026-04-25T16:05:15.053058Z","end":"2026-04-25T16:05:15.536251Z","steps":["trace[765459659] 'agreement among raft nodes before linearized reading' (duration: 482.657416ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.536275Z","caller":"traceutil/trace.go:171","msg":"trace[84596911] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:2018; }","duration":"1.067742324s","start":"2026-04-25T16:05:14.468522Z","end":"2026-04-25T16:05:15.536264Z","steps":["trace[84596911] 'agreement among raft nodes before linearized reading' (duration: 1.067660642s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.536005Z","caller":"traceutil/trace.go:171","msg":"trace[923230890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2018; }","duration":"1.32709593s","start":"2026-04-25T16:05:14.208897Z","end":"2026-04-25T16:05:15.535993Z","steps":["trace[923230890] 'agreement among raft nodes before linearized reading' (duration: 1.327012038s)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:15.535316Z","caller":"traceutil/trace.go:171","msg":"trace[343909171] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"969.126899ms","start":"2026-04-25T16:05:14.56617Z","end":"2026-04-25T16:05:15.535297Z","steps":["trace[343909171] 'process raft request' (duration: 969.071558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:15.536427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.208879Z","time spent":"1.327535261s","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:05:15.536298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:15.053038Z","time spent":"483.252421ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:05:15.536591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.566146Z","time spent":"970.32559ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:05:15.536303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:14.468502Z","time spent":"1.067794394s","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-04-25T16:05:16.934476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.03399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:05:16.934566Z","caller":"traceutil/trace.go:171","msg":"trace[1606654167] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2029; }","duration":"354.172724ms","start":"2026-04-25T16:05:16.580376Z","end":"2026-04-25T16:05:16.934549Z","steps":["trace[1606654167] 'count revisions from in-memory index tree' (duration: 353.922998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:16.934608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:16.58033Z","time spent":"354.264376ms","remote":"127.0.0.1:48238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-25T16:05:17.418981Z","caller":"traceutil/trace.go:171","msg":"trace[1765708282] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"331.771784ms","start":"2026-04-25T16:05:17.087173Z","end":"2026-04-25T16:05:17.418945Z","steps":["trace[1765708282] 'process raft request' (duration: 331.61395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:17.419143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:05:17.087062Z","time spent":"331.99476ms","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:05:17.419818Z","caller":"traceutil/trace.go:171","msg":"trace[1313537647] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"209.777806ms","start":"2026-04-25T16:05:17.210005Z","end":"2026-04-25T16:05:17.419783Z","steps":["trace[1313537647] 'read index received' (duration: 208.7374ms)","trace[1313537647] 'applied index is now lower than readState.Index' (duration: 1.028206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:05:17.419987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.961891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:05:17.42003Z","caller":"traceutil/trace.go:171","msg":"trace[2063540075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2030; }","duration":"210.052944ms","start":"2026-04-25T16:05:17.209964Z","end":"2026-04-25T16:05:17.420017Z","steps":["trace[2063540075] 'agreement among raft nodes before linearized reading' (duration: 209.935041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:17.829651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.813301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:05:17.829815Z","caller":"traceutil/trace.go:171","msg":"trace[758507528] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"247.961328ms","start":"2026-04-25T16:05:17.581837Z","end":"2026-04-25T16:05:17.829799Z","steps":["trace[758507528] 'read index received' (duration: 121.134887ms)","trace[758507528] 'applied index is now lower than readState.Index' (duration: 126.825281ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:05:17.829809Z","caller":"traceutil/trace.go:171","msg":"trace[1973814328] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"253.654032ms","start":"2026-04-25T16:05:17.57612Z","end":"2026-04-25T16:05:17.829774Z","steps":["trace[1973814328] 'process raft request' (duration: 126.644066ms)","trace[1973814328] 'compare' (duration: 126.704568ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:05:17.829993Z","caller":"traceutil/trace.go:171","msg":"trace[672086446] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"245.154816ms","start":"2026-04-25T16:05:17.584826Z","end":"2026-04-25T16:05:17.829981Z","steps":["trace[672086446] 'process raft request' (duration: 244.9239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:17.830203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.433679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-25T16:05:17.830239Z","caller":"traceutil/trace.go:171","msg":"trace[82464028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2033; }","duration":"274.47183ms","start":"2026-04-25T16:05:17.555758Z","end":"2026-04-25T16:05:17.83023Z","steps":["trace[82464028] 'agreement among raft nodes before linearized reading' (duration: 274.086461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:17.830286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.793653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-25T16:05:17.83041Z","caller":"traceutil/trace.go:171","msg":"trace[1219571862] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2033; }","duration":"275.956677ms","start":"2026-04-25T16:05:17.554426Z","end":"2026-04-25T16:05:17.830383Z","steps":["trace[1219571862] 'agreement among raft nodes before linearized reading' (duration: 275.434704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:17.830806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.183669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-25T16:05:17.830897Z","caller":"traceutil/trace.go:171","msg":"trace[748311930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2033; }","duration":"277.332633ms","start":"2026-04-25T16:05:17.553545Z","end":"2026-04-25T16:05:17.830877Z","steps":["trace[748311930] 'agreement among raft nodes before linearized reading' (duration: 276.302117ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:18.032852Z","caller":"traceutil/trace.go:171","msg":"trace[125037590] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"197.28365ms","start":"2026-04-25T16:05:17.835544Z","end":"2026-04-25T16:05:18.032828Z","steps":["trace[125037590] 'process raft request' (duration: 196.988552ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:18.033086Z","caller":"traceutil/trace.go:171","msg":"trace[277564713] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"197.348661ms","start":"2026-04-25T16:05:17.835699Z","end":"2026-04-25T16:05:18.033047Z","steps":["trace[277564713] 'process raft request' (duration: 197.068244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:18.522228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.977159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-25T16:05:18.522312Z","caller":"traceutil/trace.go:171","msg":"trace[222405922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2036; }","duration":"291.075292ms","start":"2026-04-25T16:05:18.231216Z","end":"2026-04-25T16:05:18.522291Z","steps":["trace[222405922] 'range keys from in-memory index tree' (duration: 290.800355ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:18.692817Z","caller":"traceutil/trace.go:171","msg":"trace[626199541] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"165.818ms","start":"2026-04-25T16:05:18.52697Z","end":"2026-04-25T16:05:18.692787Z","steps":["trace[626199541] 'process raft request' (duration: 165.651426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:18.929294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.194289ms","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-04-25T16:05:18.929423Z","caller":"traceutil/trace.go:171","msg":"trace[1335885362] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2037; }","duration":"138.384714ms","start":"2026-04-25T16:05:18.79102Z","end":"2026-04-25T16:05:18.929404Z","steps":["trace[1335885362] 'count revisions from in-memory index tree' (duration: 138.103106ms)"],"step_count":1}