{"level":"warn","ts":"2026-05-27T09:56:45.01075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:56:45.010827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T09:56:45.010887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:56:45.010895Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-27T09:56:45.010924Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T09:56:45.011577Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-05-27T09:56:45.011654Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T09:56:45.017961Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.11209ms"} {"level":"info","ts":"2026-05-27T09:56:45.035724Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-05-27T09:56:45.035832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T09:56:45.035858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-05-27T09:56:45.035873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T09:56:45.035886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-05-27T09:56:45.035931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-05-27T09:56:45.048351Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T09:56:45.051315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T09:56:45.052929Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T09:56:45.056199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T09:56:45.056336Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T09:56:45.056482Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:56:45.056538Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:56:45.056547Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T09:56:45.064061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-05-27T09:56:45.06436Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-27T09:56:45.065451Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T09:56:45.065624Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-27T09:56:45.065665Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-27T09:56:45.06657Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T09:56:45.066613Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T09:56:45.436891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T09:56:45.436954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T09:56:45.436977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-05-27T09:56:45.436998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-05-27T09:56:45.437015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-27T09:56:45.437049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-05-27T09:56:45.43707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-27T09:56:45.443792Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T09:56:45.44385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:56:45.443971Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:56:45.44433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:56:45.444276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T09:56:45.444419Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T09:56:45.447205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-05-27T09:56:45.449618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T09:56:45.4925Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:56:45.492859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:56:45.493023Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:56:48.179379Z","caller":"traceutil/trace.go:171","msg":"trace[1327181578] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"108.754766ms","start":"2026-05-27T09:56:48.070574Z","end":"2026-05-27T09:56:48.179328Z","steps":["trace[1327181578] 'process raft request' (duration: 16.386795ms)","trace[1327181578] 'compare' (duration: 91.85742ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:48.330385Z","caller":"traceutil/trace.go:171","msg":"trace[724941454] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"149.059155ms","start":"2026-05-27T09:56:48.181281Z","end":"2026-05-27T09:56:48.33034Z","steps":["trace[724941454] 'process raft request' (duration: 137.759111ms)","trace[724941454] 'compare' (duration: 11.11215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:48.330514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.404572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T09:56:48.330623Z","caller":"traceutil/trace.go:171","msg":"trace[988494939] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:83; }","duration":"147.588376ms","start":"2026-05-27T09:56:48.183012Z","end":"2026-05-27T09:56:48.330601Z","steps":["trace[988494939] 'agreement among raft nodes before linearized reading' (duration: 147.30548ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:49.55269Z","caller":"traceutil/trace.go:171","msg":"trace[70152284] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"126.698082ms","start":"2026-05-27T09:56:49.425957Z","end":"2026-05-27T09:56:49.552655Z","steps":["trace[70152284] 'process raft request' (duration: 104.675125ms)","trace[70152284] 'compare' (duration: 21.688809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:50.371689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.556044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:56:50.371856Z","caller":"traceutil/trace.go:171","msg":"trace[104013312] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:259; }","duration":"180.016008ms","start":"2026-05-27T09:56:50.191816Z","end":"2026-05-27T09:56:50.371832Z","steps":["trace[104013312] 'read index received' (duration: 30.641158ms)","trace[104013312] 'applied index is now lower than readState.Index' (duration: 149.37163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:50.372019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.194232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T09:56:50.372059Z","caller":"traceutil/trace.go:171","msg":"trace[1334054902] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:254; }","duration":"180.281814ms","start":"2026-05-27T09:56:50.191766Z","end":"2026-05-27T09:56:50.372048Z","steps":["trace[1334054902] 'agreement among raft nodes before linearized reading' (duration: 180.138301ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:50.372023Z","caller":"traceutil/trace.go:171","msg":"trace[2066816420] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"187.12759ms","start":"2026-05-27T09:56:50.184819Z","end":"2026-05-27T09:56:50.371946Z","steps":["trace[2066816420] 'process raft request' (duration: 37.748579ms)","trace[2066816420] 'compare' (duration: 148.395301ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:50.560951Z","caller":"traceutil/trace.go:171","msg":"trace[320598183] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"178.865276ms","start":"2026-05-27T09:56:50.382059Z","end":"2026-05-27T09:56:50.560925Z","steps":["trace[320598183] 'read index received' (duration: 107.118274ms)","trace[320598183] 'applied index is now lower than readState.Index' (duration: 71.745832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:50.561115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.032189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:56:50.561093Z","caller":"traceutil/trace.go:171","msg":"trace[915098232] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"180.960787ms","start":"2026-05-27T09:56:50.380085Z","end":"2026-05-27T09:56:50.561046Z","steps":["trace[915098232] 'process raft request' (duration: 109.077333ms)","trace[915098232] 'compare' (duration: 71.603019ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:50.561165Z","caller":"traceutil/trace.go:171","msg":"trace[2083051695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:256; }","duration":"179.151811ms","start":"2026-05-27T09:56:50.381997Z","end":"2026-05-27T09:56:50.561149Z","steps":["trace[2083051695] 'agreement among raft nodes before linearized reading' (duration: 179.035599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:54.770616Z","caller":"traceutil/trace.go:171","msg":"trace[1629692868] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"155.452041ms","start":"2026-05-27T09:56:54.615141Z","end":"2026-05-27T09:56:54.770593Z","steps":["trace[1629692868] 'read index received' (duration: 81.088937ms)","trace[1629692868] 'applied index is now lower than readState.Index' (duration: 74.361644ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:54.770733Z","caller":"traceutil/trace.go:171","msg":"trace[654860813] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"156.163495ms","start":"2026-05-27T09:56:54.614531Z","end":"2026-05-27T09:56:54.770694Z","steps":["trace[654860813] 'process raft request' (duration: 81.771591ms)","trace[654860813] 'compare' (duration: 74.16864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:54.770823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.657336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-27T09:56:54.77087Z","caller":"traceutil/trace.go:171","msg":"trace[1490815809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"155.747048ms","start":"2026-05-27T09:56:54.615106Z","end":"2026-05-27T09:56:54.770853Z","steps":["trace[1490815809] 'agreement among raft nodes before linearized reading' (duration: 155.623976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:56:54.771045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.892529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-27T09:56:54.771211Z","caller":"traceutil/trace.go:171","msg":"trace[83674376] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"105.131363ms","start":"2026-05-27T09:56:54.666055Z","end":"2026-05-27T09:56:54.771187Z","steps":["trace[83674376] 'agreement among raft nodes before linearized reading' (duration: 104.766206ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.453896Z","caller":"traceutil/trace.go:171","msg":"trace[1905489690] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"128.759202ms","start":"2026-05-27T09:56:55.325084Z","end":"2026-05-27T09:56:55.453843Z","steps":["trace[1905489690] 'process raft request' (duration: 56.324837ms)","trace[1905489690] 'compare' (duration: 72.027857ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:55.629667Z","caller":"traceutil/trace.go:171","msg":"trace[1143788769] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:375; }","duration":"113.689953ms","start":"2026-05-27T09:56:55.515955Z","end":"2026-05-27T09:56:55.629645Z","steps":["trace[1143788769] 'read index received' (duration: 8.174532ms)","trace[1143788769] 'applied index is now lower than readState.Index' (duration: 105.514631ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:55.629779Z","caller":"traceutil/trace.go:171","msg":"trace[1979134452] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"120.838445ms","start":"2026-05-27T09:56:55.508918Z","end":"2026-05-27T09:56:55.629756Z","steps":["trace[1979134452] 'process raft request' (duration: 44.88248ms)","trace[1979134452] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/kube-vip-instance; req_size:4774; } (duration: 75.397135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:55.62983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.854856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T09:56:55.629856Z","caller":"traceutil/trace.go:171","msg":"trace[215230025] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:371; }","duration":"113.922308ms","start":"2026-05-27T09:56:55.515926Z","end":"2026-05-27T09:56:55.629849Z","steps":["trace[215230025] 'agreement among raft nodes before linearized reading' (duration: 113.836156ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.802434Z","caller":"traceutil/trace.go:171","msg":"trace[1543858579] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"121.061769ms","start":"2026-05-27T09:56:55.681345Z","end":"2026-05-27T09:56:55.802407Z","steps":["trace[1543858579] 'process raft request' (duration: 121.012838ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.802745Z","caller":"traceutil/trace.go:171","msg":"trace[580575837] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"159.91836ms","start":"2026-05-27T09:56:55.64281Z","end":"2026-05-27T09:56:55.802728Z","steps":["trace[580575837] 'process raft request' (duration: 159.41444ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.802811Z","caller":"traceutil/trace.go:171","msg":"trace[1260112059] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"162.988511ms","start":"2026-05-27T09:56:55.639781Z","end":"2026-05-27T09:56:55.802769Z","steps":["trace[1260112059] 'process raft request' (duration: 98.211786ms)","trace[1260112059] 'compare' (duration: 64.065331ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:56:55.803091Z","caller":"traceutil/trace.go:171","msg":"trace[863296398] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"122.837235ms","start":"2026-05-27T09:56:55.680233Z","end":"2026-05-27T09:56:55.80307Z","steps":["trace[863296398] 'process raft request' (duration: 122.062619ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.803278Z","caller":"traceutil/trace.go:171","msg":"trace[1622110682] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"159.333818ms","start":"2026-05-27T09:56:55.643922Z","end":"2026-05-27T09:56:55.803255Z","steps":["trace[1622110682] 'read index received' (duration: 28.909023ms)","trace[1622110682] 'applied index is now lower than readState.Index' (duration: 130.415655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:56:55.803457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.722113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T09:56:55.803508Z","caller":"traceutil/trace.go:171","msg":"trace[1158131686] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:378; }","duration":"122.817195ms","start":"2026-05-27T09:56:55.680676Z","end":"2026-05-27T09:56:55.803494Z","steps":["trace[1158131686] 'agreement among raft nodes before linearized reading' (duration: 122.711653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:56:55.803458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.518622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-27T09:56:55.803682Z","caller":"traceutil/trace.go:171","msg":"trace[1058697292] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:378; }","duration":"159.726796ms","start":"2026-05-27T09:56:55.643882Z","end":"2026-05-27T09:56:55.803608Z","steps":["trace[1058697292] 'agreement among raft nodes before linearized reading' (duration: 159.492352ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.97405Z","caller":"traceutil/trace.go:171","msg":"trace[1902573671] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"117.427128ms","start":"2026-05-27T09:56:55.856587Z","end":"2026-05-27T09:56:55.974014Z","steps":["trace[1902573671] 'process raft request' (duration: 117.349367ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.974378Z","caller":"traceutil/trace.go:171","msg":"trace[629787584] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"125.486317ms","start":"2026-05-27T09:56:55.84888Z","end":"2026-05-27T09:56:55.974366Z","steps":["trace[629787584] 'process raft request' (duration: 124.942916ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:56:55.974381Z","caller":"traceutil/trace.go:171","msg":"trace[141783395] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"126.034508ms","start":"2026-05-27T09:56:55.848291Z","end":"2026-05-27T09:56:55.974325Z","steps":["trace[141783395] 'process raft request' (duration: 60.252224ms)","trace[141783395] 'compare' (duration: 64.848575ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:03.411095Z","caller":"traceutil/trace.go:171","msg":"trace[1331413689] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"118.670493ms","start":"2026-05-27T09:57:03.292388Z","end":"2026-05-27T09:57:03.411059Z","steps":["trace[1331413689] 'read index received' (duration: 118.410377ms)","trace[1331413689] 'applied index is now lower than readState.Index' (duration: 258.406µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:03.411257Z","caller":"traceutil/trace.go:171","msg":"trace[884108928] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"154.381091ms","start":"2026-05-27T09:57:03.256839Z","end":"2026-05-27T09:57:03.41122Z","steps":["trace[884108928] 'process raft request' (duration: 153.980552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:03.411318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.894927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:57:03.411367Z","caller":"traceutil/trace.go:171","msg":"trace[795273362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"119.024839ms","start":"2026-05-27T09:57:03.292327Z","end":"2026-05-27T09:57:03.411352Z","steps":["trace[795273362] 'agreement among raft nodes before linearized reading' (duration: 118.891407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:10.546075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.808348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:57:10.54619Z","caller":"traceutil/trace.go:171","msg":"trace[1335799120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:438; }","duration":"653.987343ms","start":"2026-05-27T09:57:09.892179Z","end":"2026-05-27T09:57:10.546166Z","steps":["trace[1335799120] 'range keys from in-memory index tree' (duration: 653.589544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:10.546234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:09.892152Z","time spent":"654.072575ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T09:57:10.940972Z","caller":"traceutil/trace.go:171","msg":"trace[511086007] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"335.417079ms","start":"2026-05-27T09:57:10.605523Z","end":"2026-05-27T09:57:10.94094Z","steps":["trace[511086007] 'read index received' (duration: 335.150043ms)","trace[511086007] 'applied index is now lower than readState.Index' (duration: 266.126µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:10.941154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.593222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:10.9412Z","caller":"traceutil/trace.go:171","msg":"trace[940257481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"335.708694ms","start":"2026-05-27T09:57:10.605472Z","end":"2026-05-27T09:57:10.941181Z","steps":["trace[940257481] 'agreement among raft nodes before linearized reading' (duration: 335.587222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:10.941238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:10.605448Z","time spent":"335.782755ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T09:57:10.941689Z","caller":"traceutil/trace.go:171","msg":"trace[1749025822] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"389.295446ms","start":"2026-05-27T09:57:10.552382Z","end":"2026-05-27T09:57:10.941678Z","steps":["trace[1749025822] 'process raft request' (duration: 388.382347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:10.943712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:10.552353Z","time spent":"389.363868ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:57:25.181638Z","caller":"traceutil/trace.go:171","msg":"trace[1850492898] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"101.411489ms","start":"2026-05-27T09:57:25.08019Z","end":"2026-05-27T09:57:25.181601Z","steps":["trace[1850492898] 'process raft request' (duration: 101.162795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.059041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224916217065621,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:57:26.081094Z","caller":"traceutil/trace.go:171","msg":"trace[198285077] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"554.030081ms","start":"2026-05-27T09:57:25.527032Z","end":"2026-05-27T09:57:26.081062Z","steps":["trace[198285077] 'process raft request' (duration: 553.813497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.081357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.527009Z","time spent":"554.160404ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:57:26.379599Z","caller":"traceutil/trace.go:171","msg":"trace[2061871022] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"709.26602ms","start":"2026-05-27T09:57:25.670272Z","end":"2026-05-27T09:57:26.379538Z","steps":["trace[2061871022] 'process raft request' (duration: 709.166518ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:26.379839Z","caller":"traceutil/trace.go:171","msg":"trace[1901349400] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"700.399943ms","start":"2026-05-27T09:57:25.679402Z","end":"2026-05-27T09:57:26.379802Z","steps":["trace[1901349400] 'process raft request' (duration: 700.137428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.379881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.670243Z","time spent":"709.436282ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.379962Z","caller":"traceutil/trace.go:171","msg":"trace[2027209885] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"697.160769ms","start":"2026-05-27T09:57:25.682761Z","end":"2026-05-27T09:57:26.379922Z","steps":["trace[2027209885] 'process raft request' (duration: 696.969945ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:26.380085Z","caller":"traceutil/trace.go:171","msg":"trace[1232825411] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"695.168979ms","start":"2026-05-27T09:57:25.684902Z","end":"2026-05-27T09:57:26.380071Z","steps":["trace[1232825411] 'process raft request' (duration: 694.906214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.380135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.684894Z","time spent":"695.21657ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:57:26.380016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.679387Z","time spent":"700.530175ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.380117Z","caller":"traceutil/trace.go:171","msg":"trace[474105718] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"714.236517ms","start":"2026-05-27T09:57:25.665247Z","end":"2026-05-27T09:57:26.379484Z","steps":["trace[474105718] 'process raft request' (duration: 713.944201ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:26.379607Z","caller":"traceutil/trace.go:171","msg":"trace[1163330915] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"713.148036ms","start":"2026-05-27T09:57:25.666385Z","end":"2026-05-27T09:57:26.379533Z","steps":["trace[1163330915] 'process raft request' (duration: 713.010063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.382351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.682752Z","time spent":"697.331862ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:57:26.382377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.666377Z","time spent":"715.848529ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.382959Z","caller":"traceutil/trace.go:171","msg":"trace[1236392633] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"692.343964ms","start":"2026-05-27T09:57:25.687846Z","end":"2026-05-27T09:57:26.38019Z","steps":["trace[1236392633] 'process raft request' (duration: 692.020277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.3833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.687837Z","time spent":"695.373554ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.380298Z","caller":"traceutil/trace.go:171","msg":"trace[794034843] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"682.302665ms","start":"2026-05-27T09:57:25.697985Z","end":"2026-05-27T09:57:26.380288Z","steps":["trace[794034843] 'process raft request' (duration: 681.990418ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:26.383708Z","caller":"traceutil/trace.go:171","msg":"trace[1618088393] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"703.080537ms","start":"2026-05-27T09:57:25.677134Z","end":"2026-05-27T09:57:26.380215Z","steps":["trace[1618088393] 'process raft request' (duration: 702.355372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.383794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.677106Z","time spent":"706.653827ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:57:26.384084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.697957Z","time spent":"685.619901ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.38493Z","caller":"traceutil/trace.go:171","msg":"trace[220615013] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"677.353176ms","start":"2026-05-27T09:57:25.707549Z","end":"2026-05-27T09:57:26.384902Z","steps":["trace[220615013] 'process raft request' (duration: 672.589081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.38549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.707525Z","time spent":"677.479359ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:57:26.390074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.66523Z","time spent":"724.806277ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.393575Z","caller":"traceutil/trace.go:171","msg":"trace[1729412824] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:551; }","duration":"834.95998ms","start":"2026-05-27T09:57:25.558566Z","end":"2026-05-27T09:57:26.393526Z","steps":["trace[1729412824] 'read index received' (duration: 522.327903ms)","trace[1729412824] 'applied index is now lower than readState.Index' (duration: 312.630597ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:26.39381Z","caller":"traceutil/trace.go:171","msg":"trace[656264329] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"662.091044ms","start":"2026-05-27T09:57:25.73171Z","end":"2026-05-27T09:57:26.393801Z","steps":["trace[656264329] 'process raft request' (duration: 651.432123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.393965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.731643Z","time spent":"662.223426ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:26.394105Z","caller":"traceutil/trace.go:171","msg":"trace[311374623] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"556.067142ms","start":"2026-05-27T09:57:25.838031Z","end":"2026-05-27T09:57:26.394098Z","steps":["trace[311374623] 'process raft request' (duration: 555.356448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.394138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.83801Z","time spent":"556.112583ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:57:26.394367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.793976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:57:26.394406Z","caller":"traceutil/trace.go:171","msg":"trace[1037619075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"835.867758ms","start":"2026-05-27T09:57:25.55853Z","end":"2026-05-27T09:57:26.394398Z","steps":["trace[1037619075] 'agreement among raft nodes before linearized reading' (duration: 835.726736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.394389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.051071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T09:57:26.39443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.558516Z","time spent":"835.909439ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T09:57:26.394463Z","caller":"traceutil/trace.go:171","msg":"trace[53256329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"790.187153ms","start":"2026-05-27T09:57:25.60426Z","end":"2026-05-27T09:57:26.394447Z","steps":["trace[53256329] 'agreement among raft nodes before linearized reading' (duration: 790.02338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.394525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.604232Z","time spent":"790.285975ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T09:57:26.394563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.492423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:57:26.39467Z","caller":"traceutil/trace.go:171","msg":"trace[1950737472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"605.660825ms","start":"2026-05-27T09:57:25.788991Z","end":"2026-05-27T09:57:26.394652Z","steps":["trace[1950737472] 'agreement among raft nodes before linearized reading' (duration: 605.335839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:26.394743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:25.788965Z","time spent":"605.764918ms","remote":"127.0.0.1:50064","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":"info","ts":"2026-05-27T09:57:28.646828Z","caller":"traceutil/trace.go:171","msg":"trace[663856755] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"218.43963ms","start":"2026-05-27T09:57:28.428357Z","end":"2026-05-27T09:57:28.646797Z","steps":["trace[663856755] 'process raft request' (duration: 190.713371ms)","trace[663856755] 'compare' (duration: 27.374822ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:31.45723Z","caller":"traceutil/trace.go:171","msg":"trace[1553174813] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"185.430286ms","start":"2026-05-27T09:57:31.271753Z","end":"2026-05-27T09:57:31.457184Z","steps":["trace[1553174813] 'process raft request' (duration: 178.767344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:32.846535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.10421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:57:32.846995Z","caller":"traceutil/trace.go:171","msg":"trace[124144620] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"243.502696ms","start":"2026-05-27T09:57:32.603466Z","end":"2026-05-27T09:57:32.846969Z","steps":["trace[124144620] 'read index received' (duration: 120.531149ms)","trace[124144620] 'applied index is now lower than readState.Index' (duration: 122.969597ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:32.847035Z","caller":"traceutil/trace.go:171","msg":"trace[471911023] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"385.209015ms","start":"2026-05-27T09:57:32.461794Z","end":"2026-05-27T09:57:32.847003Z","steps":["trace[471911023] 'process raft request' (duration: 262.508704ms)","trace[471911023] 'compare' (duration: 121.64941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:32.847148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.67532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:32.847492Z","caller":"traceutil/trace.go:171","msg":"trace[1483661107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"244.050948ms","start":"2026-05-27T09:57:32.603422Z","end":"2026-05-27T09:57:32.847473Z","steps":["trace[1483661107] 'agreement among raft nodes before linearized reading' (duration: 243.672009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:32.847667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:32.461759Z","time spent":"385.608843ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:57:33.43428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.125301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:57:33.434667Z","caller":"traceutil/trace.go:171","msg":"trace[1108970891] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"587.434824ms","start":"2026-05-27T09:57:32.847208Z","end":"2026-05-27T09:57:33.434643Z","steps":["trace[1108970891] 'read index received' (duration: 130.837074ms)","trace[1108970891] 'applied index is now lower than readState.Index' (duration: 456.59612ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:33.434642Z","caller":"traceutil/trace.go:171","msg":"trace[1297708086] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"739.517018ms","start":"2026-05-27T09:57:32.695075Z","end":"2026-05-27T09:57:33.434592Z","steps":["trace[1297708086] 'process raft request' (duration: 282.955789ms)","trace[1297708086] 'compare' (duration: 455.897765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:33.434842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:32.695051Z","time spent":"739.692001ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:57:33.435387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.07786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l85s6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-27T09:57:33.435519Z","caller":"traceutil/trace.go:171","msg":"trace[327808225] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l85s6; range_end:; response_count:1; response_revision:645; }","duration":"226.314726ms","start":"2026-05-27T09:57:33.20917Z","end":"2026-05-27T09:57:33.435485Z","steps":["trace[327808225] 'agreement among raft nodes before linearized reading' (duration: 225.972188ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:33.43539Z","caller":"traceutil/trace.go:171","msg":"trace[126076274] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"522.339973ms","start":"2026-05-27T09:57:32.913012Z","end":"2026-05-27T09:57:33.435352Z","steps":["trace[126076274] 'process raft request' (duration: 521.427146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:33.435634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.256178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:57:33.436146Z","caller":"traceutil/trace.go:171","msg":"trace[358407705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"767.637865ms","start":"2026-05-27T09:57:32.668165Z","end":"2026-05-27T09:57:33.435802Z","steps":["trace[358407705] 'agreement among raft nodes before linearized reading' (duration: 766.618915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:33.436359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:32.668127Z","time spent":"768.218207ms","remote":"127.0.0.1:50064","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-05-27T09:57:33.436323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.433884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T09:57:33.436263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:32.912978Z","time spent":"522.768283ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:57:33.43646Z","caller":"traceutil/trace.go:171","msg":"trace[1114896216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"138.640848ms","start":"2026-05-27T09:57:33.297786Z","end":"2026-05-27T09:57:33.436427Z","steps":["trace[1114896216] 'agreement among raft nodes before linearized reading' (duration: 137.402323ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:33.587489Z","caller":"traceutil/trace.go:171","msg":"trace[986397139] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"145.201528ms","start":"2026-05-27T09:57:33.442255Z","end":"2026-05-27T09:57:33.587457Z","steps":["trace[986397139] 'process raft request' (duration: 119.807615ms)","trace[986397139] 'compare' (duration: 25.23971ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:57:33.63008Z","caller":"traceutil/trace.go:171","msg":"trace[1645530027] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"187.614928ms","start":"2026-05-27T09:57:33.442441Z","end":"2026-05-27T09:57:33.630056Z","steps":["trace[1645530027] 'process raft request' (duration: 187.339493ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:33.630426Z","caller":"traceutil/trace.go:171","msg":"trace[1406246503] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"180.363305ms","start":"2026-05-27T09:57:33.450001Z","end":"2026-05-27T09:57:33.630364Z","steps":["trace[1406246503] 'process raft request' (duration: 179.983496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:33.630659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.479487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:33.630717Z","caller":"traceutil/trace.go:171","msg":"trace[1149270730] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:648; }","duration":"183.575909ms","start":"2026-05-27T09:57:33.447121Z","end":"2026-05-27T09:57:33.630697Z","steps":["trace[1149270730] 'agreement among raft nodes before linearized reading' (duration: 183.438056ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:33.631032Z","caller":"traceutil/trace.go:171","msg":"trace[485046790] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"183.0961ms","start":"2026-05-27T09:57:33.447165Z","end":"2026-05-27T09:57:33.630261Z","steps":["trace[485046790] 'read index received' (duration: 114.880538ms)","trace[485046790] 'applied index is now lower than readState.Index' (duration: 68.214032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:34.619893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224916217065816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:57:34.721441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033339862s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:34.72166Z","caller":"traceutil/trace.go:171","msg":"trace[2116060771] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:648; }","duration":"1.033583887s","start":"2026-05-27T09:57:33.688053Z","end":"2026-05-27T09:57:34.721637Z","steps":["trace[2116060771] 'range keys from in-memory index tree' (duration: 1.033290541s)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:34.76785Z","caller":"traceutil/trace.go:171","msg":"trace[1684378746] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"744.103718ms","start":"2026-05-27T09:57:34.02367Z","end":"2026-05-27T09:57:34.767774Z","steps":["trace[1684378746] 'process raft request' (duration: 739.12518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:34.768081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:34.023632Z","time spent":"744.321733ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:57:34.778772Z","caller":"traceutil/trace.go:171","msg":"trace[1185860739] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"659.147845ms","start":"2026-05-27T09:57:34.119407Z","end":"2026-05-27T09:57:34.778555Z","steps":["trace[1185860739] 'read index received' (duration: 642.948044ms)","trace[1185860739] 'applied index is now lower than readState.Index' (duration: 16.197721ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:34.779916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.452451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:57:34.780001Z","caller":"traceutil/trace.go:171","msg":"trace[782693146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"660.617374ms","start":"2026-05-27T09:57:34.119361Z","end":"2026-05-27T09:57:34.779979Z","steps":["trace[782693146] 'agreement among raft nodes before linearized reading' (duration: 660.304928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:34.780046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:34.119339Z","time spent":"660.698705ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T09:57:34.780478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.691013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:34.780503Z","caller":"traceutil/trace.go:171","msg":"trace[1310142876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"174.765704ms","start":"2026-05-27T09:57:34.605727Z","end":"2026-05-27T09:57:34.780493Z","steps":["trace[1310142876] 'agreement among raft nodes before linearized reading' (duration: 174.724743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:34.781101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.991795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:57:34.781227Z","caller":"traceutil/trace.go:171","msg":"trace[916550262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"252.70077ms","start":"2026-05-27T09:57:34.528504Z","end":"2026-05-27T09:57:34.781205Z","steps":["trace[916550262] 'agreement among raft nodes before linearized reading' (duration: 251.796402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.04498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.630739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:57:50.045101Z","caller":"traceutil/trace.go:171","msg":"trace[1627569588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"384.795501ms","start":"2026-05-27T09:57:49.66028Z","end":"2026-05-27T09:57:50.045075Z","steps":["trace[1627569588] 'range keys from in-memory index tree' (duration: 384.400984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.045102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.150519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:57:50.045214Z","caller":"traceutil/trace.go:171","msg":"trace[1857013449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"343.320343ms","start":"2026-05-27T09:57:49.701869Z","end":"2026-05-27T09:57:50.04519Z","steps":["trace[1857013449] 'range keys from in-memory index tree' (duration: 342.951245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.045261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.701845Z","time spent":"343.407165ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T09:57:50.045153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.660265Z","time spent":"384.877224ms","remote":"127.0.0.1:50064","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-05-27T09:57:50.045436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.970768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T09:57:50.045502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.340519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-n2qss\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-27T09:57:50.045549Z","caller":"traceutil/trace.go:171","msg":"trace[802691212] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-n2qss; range_end:; response_count:1; response_revision:808; }","duration":"725.41018ms","start":"2026-05-27T09:57:49.320124Z","end":"2026-05-27T09:57:50.045534Z","steps":["trace[802691212] 'range keys from in-memory index tree' (duration: 725.178806ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:50.045544Z","caller":"traceutil/trace.go:171","msg":"trace[1480369226] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:808; }","duration":"418.147131ms","start":"2026-05-27T09:57:49.627371Z","end":"2026-05-27T09:57:50.045519Z","steps":["trace[1480369226] 'count revisions from in-memory index tree' (duration: 417.858985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.045577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.320104Z","time spent":"725.466712ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-n2qss\" "} {"level":"warn","ts":"2026-05-27T09:57:50.045593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.627348Z","time spent":"418.234372ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-27T09:57:50.045908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.609654ms","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-05-27T09:57:50.045956Z","caller":"traceutil/trace.go:171","msg":"trace[821459881] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:808; }","duration":"653.862058ms","start":"2026-05-27T09:57:49.392081Z","end":"2026-05-27T09:57:50.045943Z","steps":["trace[821459881] 'count revisions from in-memory index tree' (duration: 652.521811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.046279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.392023Z","time spent":"654.242126ms","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-27T09:57:50.046358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.759408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T09:57:50.04642Z","caller":"traceutil/trace.go:171","msg":"trace[1445153943] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:808; }","duration":"743.012633ms","start":"2026-05-27T09:57:49.303389Z","end":"2026-05-27T09:57:50.046402Z","steps":["trace[1445153943] 'range keys from in-memory index tree' (duration: 742.554764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:57:50.046454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:57:49.303365Z","time spent":"743.080574ms","remote":"127.0.0.1:49826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"info","ts":"2026-05-27T09:57:50.2376Z","caller":"traceutil/trace.go:171","msg":"trace[601911958] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"186.454142ms","start":"2026-05-27T09:57:50.051116Z","end":"2026-05-27T09:57:50.237571Z","steps":["trace[601911958] 'process raft request' (duration: 186.238308ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:50.237889Z","caller":"traceutil/trace.go:171","msg":"trace[1731347100] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"184.777379ms","start":"2026-05-27T09:57:50.053089Z","end":"2026-05-27T09:57:50.237866Z","steps":["trace[1731347100] 'process raft request' (duration: 184.438312ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:50.237981Z","caller":"traceutil/trace.go:171","msg":"trace[892998120] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:836; }","duration":"178.563284ms","start":"2026-05-27T09:57:50.059396Z","end":"2026-05-27T09:57:50.237959Z","steps":["trace[892998120] 'read index received' (duration: 131.291287ms)","trace[892998120] 'applied index is now lower than readState.Index' (duration: 47.270907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:57:50.238122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.702416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:57:50.23816Z","caller":"traceutil/trace.go:171","msg":"trace[417836730] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:811; }","duration":"178.783308ms","start":"2026-05-27T09:57:50.059364Z","end":"2026-05-27T09:57:50.238147Z","steps":["trace[417836730] 'agreement among raft nodes before linearized reading' (duration: 178.707997ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:57:50.238116Z","caller":"traceutil/trace.go:171","msg":"trace[2026010271] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"184.638536ms","start":"2026-05-27T09:57:50.05341Z","end":"2026-05-27T09:57:50.238049Z","steps":["trace[2026010271] 'process raft request' (duration: 184.360801ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:25.55775Z","caller":"traceutil/trace.go:171","msg":"trace[389777329] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"407.286233ms","start":"2026-05-27T09:58:25.15043Z","end":"2026-05-27T09:58:25.557716Z","steps":["trace[389777329] 'read index received' (duration: 406.907946ms)","trace[389777329] 'applied index is now lower than readState.Index' (duration: 377.277µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:58:25.558151Z","caller":"traceutil/trace.go:171","msg":"trace[1063619128] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"416.68618ms","start":"2026-05-27T09:58:25.14145Z","end":"2026-05-27T09:58:25.558136Z","steps":["trace[1063619128] 'process raft request' (duration: 415.975696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:25.55829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:25.141434Z","time spent":"416.759712ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:58:25.558575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.153162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T09:58:25.559375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.192039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:58:25.559499Z","caller":"traceutil/trace.go:171","msg":"trace[1068797660] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1024; }","duration":"283.362143ms","start":"2026-05-27T09:58:25.276097Z","end":"2026-05-27T09:58:25.559459Z","steps":["trace[1068797660] 'agreement among raft nodes before linearized reading' (duration: 283.114538ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:25.559513Z","caller":"traceutil/trace.go:171","msg":"trace[221685984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"409.060449ms","start":"2026-05-27T09:58:25.150362Z","end":"2026-05-27T09:58:25.559422Z","steps":["trace[221685984] 'agreement among raft nodes before linearized reading' (duration: 408.117131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:25.559802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:25.150342Z","time spent":"409.387986ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T09:58:26.701277Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.136270876s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T09:58:26.774047Z","caller":"traceutil/trace.go:171","msg":"trace[1642948780] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.209142655s","start":"2026-05-27T09:58:25.564872Z","end":"2026-05-27T09:58:26.774015Z","steps":["trace[1642948780] 'process raft request' (duration: 1.136692855s)","trace[1642948780] 'compare' (duration: 72.290167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:58:26.774241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:25.564849Z","time spent":"1.209292998s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:58:26.832908Z","caller":"traceutil/trace.go:171","msg":"trace[225160227] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"405.879276ms","start":"2026-05-27T09:58:26.426992Z","end":"2026-05-27T09:58:26.832871Z","steps":["trace[225160227] 'read index received' (duration: 274.538106ms)","trace[225160227] 'applied index is now lower than readState.Index' (duration: 131.33961ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:58:26.833074Z","caller":"traceutil/trace.go:171","msg":"trace[1848777209] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.194588783s","start":"2026-05-27T09:58:25.638472Z","end":"2026-05-27T09:58:26.833061Z","steps":["trace[1848777209] 'process raft request' (duration: 1.194227136s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.833242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:25.638445Z","time spent":"1.194681805s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:58:26.833394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.957786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:58:26.833482Z","caller":"traceutil/trace.go:171","msg":"trace[813586461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"389.114549ms","start":"2026-05-27T09:58:26.44435Z","end":"2026-05-27T09:58:26.833465Z","steps":["trace[813586461] 'agreement among raft nodes before linearized reading' (duration: 388.888355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.833522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:26.444321Z","time spent":"389.193051ms","remote":"127.0.0.1:50064","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-05-27T09:58:26.833664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.683471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:58:26.833686Z","caller":"traceutil/trace.go:171","msg":"trace[1223266486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"406.742162ms","start":"2026-05-27T09:58:26.426938Z","end":"2026-05-27T09:58:26.83368Z","steps":["trace[1223266486] 'agreement among raft nodes before linearized reading' (duration: 406.678691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.833704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:26.426914Z","time spent":"406.786373ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T09:58:26.8338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.677388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:58:26.833852Z","caller":"traceutil/trace.go:171","msg":"trace[1500360023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"228.77997ms","start":"2026-05-27T09:58:26.60506Z","end":"2026-05-27T09:58:26.83384Z","steps":["trace[1500360023] 'agreement among raft nodes before linearized reading' (duration: 228.702768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.833915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.491688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T09:58:26.83395Z","caller":"traceutil/trace.go:171","msg":"trace[32162818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"382.562339ms","start":"2026-05-27T09:58:26.451376Z","end":"2026-05-27T09:58:26.833939Z","steps":["trace[32162818] 'agreement among raft nodes before linearized reading' (duration: 382.472468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.833976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:26.451368Z","time spent":"382.60364ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T09:58:27.139218Z","caller":"traceutil/trace.go:171","msg":"trace[1512623289] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"299.358342ms","start":"2026-05-27T09:58:26.839814Z","end":"2026-05-27T09:58:27.139173Z","steps":["trace[1512623289] 'process raft request' (duration: 297.012405ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:27.139266Z","caller":"traceutil/trace.go:171","msg":"trace[2048422825] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"299.330301ms","start":"2026-05-27T09:58:26.839916Z","end":"2026-05-27T09:58:27.139247Z","steps":["trace[2048422825] 'process raft request' (duration: 299.081576ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:27.139701Z","caller":"traceutil/trace.go:171","msg":"trace[815187773] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"299.198599ms","start":"2026-05-27T09:58:26.84002Z","end":"2026-05-27T09:58:27.139219Z","steps":["trace[815187773] 'process raft request' (duration: 299.037276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:47.784628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.172685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:58:47.784741Z","caller":"traceutil/trace.go:171","msg":"trace[1718229460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"105.343618ms","start":"2026-05-27T09:58:47.679371Z","end":"2026-05-27T09:58:47.784715Z","steps":["trace[1718229460] 'range keys from in-memory index tree' (duration: 104.94654ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:51.134219Z","caller":"traceutil/trace.go:171","msg":"trace[213867525] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"172.933937ms","start":"2026-05-27T09:58:50.961254Z","end":"2026-05-27T09:58:51.134188Z","steps":["trace[213867525] 'process raft request' (duration: 154.101564ms)","trace[213867525] 'compare' (duration: 18.224862ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:58:51.439934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.92176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:58:51.440015Z","caller":"traceutil/trace.go:171","msg":"trace[1404896838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"109.057172ms","start":"2026-05-27T09:58:51.330941Z","end":"2026-05-27T09:58:51.439998Z","steps":["trace[1404896838] 'range keys from in-memory index tree' (duration: 108.759077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:52.157358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.796927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:58:52.157443Z","caller":"traceutil/trace.go:171","msg":"trace[36054683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"339.937289ms","start":"2026-05-27T09:58:51.817486Z","end":"2026-05-27T09:58:52.157423Z","steps":["trace[36054683] 'range keys from in-memory index tree' (duration: 339.620024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:52.1575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:51.817466Z","time spent":"340.025372ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T09:58:52.583593Z","caller":"traceutil/trace.go:171","msg":"trace[302430467] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"421.973495ms","start":"2026-05-27T09:58:52.161568Z","end":"2026-05-27T09:58:52.583542Z","steps":["trace[302430467] 'process raft request' (duration: 421.786022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:52.583749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:52.161546Z","time spent":"422.12354ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:58:53.353376Z","caller":"traceutil/trace.go:171","msg":"trace[269658109] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"212.606675ms","start":"2026-05-27T09:58:53.140731Z","end":"2026-05-27T09:58:53.353338Z","steps":["trace[269658109] 'process raft request' (duration: 212.32231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:54.075057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.36133ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:58:54.075183Z","caller":"traceutil/trace.go:171","msg":"trace[1008232169] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1113; }","duration":"387.501682ms","start":"2026-05-27T09:58:53.687664Z","end":"2026-05-27T09:58:54.075165Z","steps":["trace[1008232169] 'range keys from in-memory index tree' (duration: 387.34239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:54.075597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.20764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:58:54.07606Z","caller":"traceutil/trace.go:171","msg":"trace[1077749841] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"494.670567ms","start":"2026-05-27T09:58:53.581362Z","end":"2026-05-27T09:58:54.076032Z","steps":["trace[1077749841] 'process raft request' (duration: 126.860596ms)","trace[1077749841] 'compare' (duration: 367.005346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:58:54.076234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:53.581346Z","time spent":"494.786449ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:58:54.076308Z","caller":"traceutil/trace.go:171","msg":"trace[147952606] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"494.526355ms","start":"2026-05-27T09:58:53.581749Z","end":"2026-05-27T09:58:54.076275Z","steps":["trace[147952606] 'process raft request' (duration: 494.270739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:54.076428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:53.58174Z","time spent":"494.635797ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:58:54.076603Z","caller":"traceutil/trace.go:171","msg":"trace[578440672] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"363.173109ms","start":"2026-05-27T09:58:53.712923Z","end":"2026-05-27T09:58:54.076096Z","steps":["trace[578440672] 'read index received' (duration: 71.431µs)","trace[578440672] 'applied index is now lower than readState.Index' (duration: 363.099818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:58:54.076641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.698661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T09:58:54.076736Z","caller":"traceutil/trace.go:171","msg":"trace[1642800641] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1115; }","duration":"363.868994ms","start":"2026-05-27T09:58:53.712844Z","end":"2026-05-27T09:58:54.076713Z","steps":["trace[1642800641] 'agreement among raft nodes before linearized reading' (duration: 363.68833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:54.076789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:58:53.712811Z","time spent":"363.961906ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-27T09:59:10.653064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224916217067122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:59:10.658618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.786864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T09:59:10.658683Z","caller":"traceutil/trace.go:171","msg":"trace[1751789280] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1217; }","duration":"867.866565ms","start":"2026-05-27T09:59:09.790802Z","end":"2026-05-27T09:59:10.658669Z","steps":["trace[1751789280] 'count revisions from in-memory index tree' (duration: 867.716783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:10.658709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:09.790761Z","time spent":"867.940727ms","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-27T09:59:11.119182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.301237957s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T09:59:11.119599Z","caller":"traceutil/trace.go:171","msg":"trace[1583807395] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"967.350998ms","start":"2026-05-27T09:59:10.152227Z","end":"2026-05-27T09:59:11.119578Z","steps":["trace[1583807395] 'read index received' (duration: 967.114733ms)","trace[1583807395] 'applied index is now lower than readState.Index' (duration: 235.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:11.11969Z","caller":"traceutil/trace.go:171","msg":"trace[27211247] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"1.301797938s","start":"2026-05-27T09:59:09.817838Z","end":"2026-05-27T09:59:11.119636Z","steps":["trace[27211247] 'process raft request' (duration: 1.301584464s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.119775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.712714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:11.119814Z","caller":"traceutil/trace.go:171","msg":"trace[1806142965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"515.795825ms","start":"2026-05-27T09:59:10.604007Z","end":"2026-05-27T09:59:11.119803Z","steps":["trace[1806142965] 'agreement among raft nodes before linearized reading' (duration: 515.717403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.119844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:10.603993Z","time spent":"515.846085ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T09:59:11.119854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.609092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T09:59:11.119888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:09.817811Z","time spent":"1.301970901s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:11.119887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.902463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T09:59:11.119928Z","caller":"traceutil/trace.go:171","msg":"trace[889170493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"967.750775ms","start":"2026-05-27T09:59:10.152162Z","end":"2026-05-27T09:59:11.119913Z","steps":["trace[889170493] 'agreement among raft nodes before linearized reading' (duration: 967.50818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.119958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:10.152145Z","time spent":"967.808777ms","remote":"127.0.0.1:50064","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-05-27T09:59:11.119959Z","caller":"traceutil/trace.go:171","msg":"trace[1611645077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"314.026975ms","start":"2026-05-27T09:59:10.805914Z","end":"2026-05-27T09:59:11.119941Z","steps":["trace[1611645077] 'agreement among raft nodes before linearized reading' (duration: 313.797401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.119996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:10.805882Z","time spent":"314.107696ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T09:59:11.120208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.491391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-27T09:59:11.120263Z","caller":"traceutil/trace.go:171","msg":"trace[2015149073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"966.562392ms","start":"2026-05-27T09:59:10.153671Z","end":"2026-05-27T09:59:11.120233Z","steps":["trace[2015149073] 'agreement among raft nodes before linearized reading' (duration: 966.016631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.120282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:10.153646Z","time spent":"966.631644ms","remote":"127.0.0.1:50064","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":"info","ts":"2026-05-27T09:59:11.453212Z","caller":"traceutil/trace.go:171","msg":"trace[1140223038] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"329.168776ms","start":"2026-05-27T09:59:11.124019Z","end":"2026-05-27T09:59:11.453187Z","steps":["trace[1140223038] 'process raft request' (duration: 322.395112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.453767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:11.124002Z","time spent":"329.276589ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:11.486946Z","caller":"traceutil/trace.go:171","msg":"trace[1882781257] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"362.765043ms","start":"2026-05-27T09:59:11.124127Z","end":"2026-05-27T09:59:11.486892Z","steps":["trace[1882781257] 'process raft request' (duration: 362.528128ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:11.487037Z","caller":"traceutil/trace.go:171","msg":"trace[402601105] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1266; }","duration":"256.7044ms","start":"2026-05-27T09:59:11.230316Z","end":"2026-05-27T09:59:11.48702Z","steps":["trace[402601105] 'read index received' (duration: 216.40235ms)","trace[402601105] 'applied index is now lower than readState.Index' (duration: 40.30109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:11.48724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.930074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:59:11.487283Z","caller":"traceutil/trace.go:171","msg":"trace[1916931025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"256.979075ms","start":"2026-05-27T09:59:11.230293Z","end":"2026-05-27T09:59:11.487272Z","steps":["trace[1916931025] 'agreement among raft nodes before linearized reading' (duration: 256.785372ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:11.487439Z","caller":"traceutil/trace.go:171","msg":"trace[893797623] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"363.047017ms","start":"2026-05-27T09:59:11.124366Z","end":"2026-05-27T09:59:11.487413Z","steps":["trace[893797623] 'process raft request' (duration: 362.392974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:11.487639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:11.124359Z","time spent":"363.167089ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:11.487527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:11.124105Z","time spent":"362.963716ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:23.78912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.807449ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:23.789268Z","caller":"traceutil/trace.go:171","msg":"trace[1526872657] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1260; }","duration":"100.970052ms","start":"2026-05-27T09:59:23.688278Z","end":"2026-05-27T09:59:23.789248Z","steps":["trace[1526872657] 'range keys from in-memory index tree' (duration: 100.793648ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:23.791918Z","caller":"traceutil/trace.go:171","msg":"trace[114707650] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"171.145662ms","start":"2026-05-27T09:59:23.620755Z","end":"2026-05-27T09:59:23.791901Z","steps":["trace[114707650] 'process raft request' (duration: 170.735294ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:23.791928Z","caller":"traceutil/trace.go:171","msg":"trace[458605290] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"170.761426ms","start":"2026-05-27T09:59:23.621153Z","end":"2026-05-27T09:59:23.791914Z","steps":["trace[458605290] 'process raft request' (duration: 170.583712ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:27.817314Z","caller":"traceutil/trace.go:171","msg":"trace[2006875586] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"169.569782ms","start":"2026-05-27T09:59:27.64771Z","end":"2026-05-27T09:59:27.81728Z","steps":["trace[2006875586] 'process raft request' (duration: 169.395708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:28.367687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.835979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:28.367782Z","caller":"traceutil/trace.go:171","msg":"trace[1810296009] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1341; }","duration":"666.550134ms","start":"2026-05-27T09:59:27.701215Z","end":"2026-05-27T09:59:28.367765Z","steps":["trace[1810296009] 'read index received' (duration: 115.942038ms)","trace[1810296009] 'applied index is now lower than readState.Index' (duration: 550.607036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:28.368276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.188715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T09:59:28.368355Z","caller":"traceutil/trace.go:171","msg":"trace[1380262812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"557.34419ms","start":"2026-05-27T09:59:27.810993Z","end":"2026-05-27T09:59:28.368337Z","steps":["trace[1380262812] 'agreement among raft nodes before linearized reading' (duration: 557.118414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:28.368473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.046606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:59:28.368576Z","caller":"traceutil/trace.go:171","msg":"trace[1333237006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"549.204558ms","start":"2026-05-27T09:59:27.819354Z","end":"2026-05-27T09:59:28.368558Z","steps":["trace[1333237006] 'agreement among raft nodes before linearized reading' (duration: 548.885551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:28.368597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.38034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T09:59:28.368694Z","caller":"traceutil/trace.go:171","msg":"trace[497670367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"667.484313ms","start":"2026-05-27T09:59:27.701199Z","end":"2026-05-27T09:59:28.368683Z","steps":["trace[497670367] 'agreement among raft nodes before linearized reading' (duration: 667.273679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:28.368737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:27.701142Z","time spent":"667.585305ms","remote":"127.0.0.1:50064","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-05-27T09:59:28.368746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:27.819331Z","time spent":"549.341101ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T09:59:28.368519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:27.81096Z","time spent":"557.545384ms","remote":"127.0.0.1:50064","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-05-27T09:59:28.368437Z","caller":"traceutil/trace.go:171","msg":"trace[572400529] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"701.274842ms","start":"2026-05-27T09:59:27.667141Z","end":"2026-05-27T09:59:28.368416Z","steps":["trace[572400529] 'process raft request' (duration: 198.579636ms)","trace[572400529] 'compare' (duration: 501.354299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:28.369497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.490002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:59:28.369585Z","caller":"traceutil/trace.go:171","msg":"trace[876451685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"556.594494ms","start":"2026-05-27T09:59:27.812974Z","end":"2026-05-27T09:59:28.369569Z","steps":["trace[876451685] 'agreement among raft nodes before linearized reading' (duration: 556.439121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:28.369645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:27.812963Z","time spent":"556.670216ms","remote":"127.0.0.1:50064","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-05-27T09:59:28.369209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:27.667108Z","time spent":"702.032357ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:28.991618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.707293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:28.992091Z","caller":"traceutil/trace.go:171","msg":"trace[1594962332] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"618.197196ms","start":"2026-05-27T09:59:28.373806Z","end":"2026-05-27T09:59:28.992003Z","steps":["trace[1594962332] 'process raft request' (duration: 495.963103ms)","trace[1594962332] 'compare' (duration: 121.150381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:28.99223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:28.37379Z","time spent":"618.386019ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:29.16333Z","caller":"traceutil/trace.go:171","msg":"trace[585477792] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"789.453531ms","start":"2026-05-27T09:59:28.373832Z","end":"2026-05-27T09:59:29.163285Z","steps":["trace[585477792] 'process raft request' (duration: 752.396496ms)","trace[585477792] 'compare' (duration: 36.79839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:29.163498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:28.373812Z","time spent":"789.602014ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:29.163785Z","caller":"traceutil/trace.go:171","msg":"trace[1629832187] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"789.866009ms","start":"2026-05-27T09:59:28.373895Z","end":"2026-05-27T09:59:29.163761Z","steps":["trace[1629832187] 'process raft request' (duration: 789.320267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:29.163937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:28.373891Z","time spent":"789.960811ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:29.164083Z","caller":"traceutil/trace.go:171","msg":"trace[1302473732] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1343; }","duration":"523.04694ms","start":"2026-05-27T09:59:28.641024Z","end":"2026-05-27T09:59:29.164071Z","steps":["trace[1302473732] 'read index received' (duration: 228.691634ms)","trace[1302473732] 'applied index is now lower than readState.Index' (duration: 294.351706ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:29.164218Z","caller":"traceutil/trace.go:171","msg":"trace[1226485637] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"788.974701ms","start":"2026-05-27T09:59:28.375233Z","end":"2026-05-27T09:59:29.164208Z","steps":["trace[1226485637] 'process raft request' (duration: 788.449931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:29.164277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:28.375227Z","time spent":"789.013923ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:29.164395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.052586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:29.164437Z","caller":"traceutil/trace.go:171","msg":"trace[1207732254] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1296; }","duration":"142.144107ms","start":"2026-05-27T09:59:29.02228Z","end":"2026-05-27T09:59:29.164424Z","steps":["trace[1207732254] 'agreement among raft nodes before linearized reading' (duration: 142.070536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:29.164463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.405066ms","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":"info","ts":"2026-05-27T09:59:29.164543Z","caller":"traceutil/trace.go:171","msg":"trace[1260380010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1296; }","duration":"523.570579ms","start":"2026-05-27T09:59:28.640952Z","end":"2026-05-27T09:59:29.164523Z","steps":["trace[1260380010] 'agreement among raft nodes before linearized reading' (duration: 523.396436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:29.164586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:28.640931Z","time spent":"523.644831ms","remote":"127.0.0.1:35184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-27T09:59:29.363551Z","caller":"traceutil/trace.go:171","msg":"trace[16949284] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"100.355959ms","start":"2026-05-27T09:59:29.263155Z","end":"2026-05-27T09:59:29.363511Z","steps":["trace[16949284] 'process raft request' (duration: 32.749829ms)","trace[16949284] 'compare' (duration: 67.351605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:30.743467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.947978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:30.743672Z","caller":"traceutil/trace.go:171","msg":"trace[1036599727] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"758.337384ms","start":"2026-05-27T09:59:29.985316Z","end":"2026-05-27T09:59:30.743653Z","steps":["trace[1036599727] 'process raft request' (duration: 758.283593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:30.743732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:29.985297Z","time spent":"758.406065ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:59:30.743905Z","caller":"traceutil/trace.go:171","msg":"trace[1317294019] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"758.63069ms","start":"2026-05-27T09:59:29.985262Z","end":"2026-05-27T09:59:30.743892Z","steps":["trace[1317294019] 'process raft request' (duration: 183.163851ms)","trace[1317294019] 'compare' (duration: 574.845017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:30.743946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:29.985241Z","time spent":"758.68628ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:59:31.249116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224916217067316,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:59:31.39807Z","caller":"traceutil/trace.go:171","msg":"trace[1308867112] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"1.023047661s","start":"2026-05-27T09:59:30.375Z","end":"2026-05-27T09:59:31.398047Z","steps":["trace[1308867112] 'process raft request' (duration: 1.022919318s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.398211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:30.374973Z","time spent":"1.023164993s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:31.955367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.475694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:31.955489Z","caller":"traceutil/trace.go:171","msg":"trace[397705413] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"361.865594ms","start":"2026-05-27T09:59:31.593613Z","end":"2026-05-27T09:59:31.955479Z","steps":["trace[397705413] 'process raft request' (duration: 361.827033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.955529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.593594Z","time spent":"361.913136ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:31.955548Z","caller":"traceutil/trace.go:171","msg":"trace[1212302507] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"1.207425447s","start":"2026-05-27T09:59:30.748095Z","end":"2026-05-27T09:59:31.95552Z","steps":["trace[1212302507] 'read index received' (duration: 650.089498ms)","trace[1212302507] 'applied index is now lower than readState.Index' (duration: 557.332399ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:31.95573Z","caller":"traceutil/trace.go:171","msg":"trace[546796777] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.207416997s","start":"2026-05-27T09:59:30.748281Z","end":"2026-05-27T09:59:31.955698Z","steps":["trace[546796777] 'process raft request' (duration: 951.566595ms)","trace[546796777] 'compare' (duration: 255.386162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:31.955863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:30.748257Z","time spent":"1.207547989s","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:59:31.955869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207757093s","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-05-27T09:59:31.955923Z","caller":"traceutil/trace.go:171","msg":"trace[1722940499] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1306; }","duration":"1.207837045s","start":"2026-05-27T09:59:30.74807Z","end":"2026-05-27T09:59:31.955907Z","steps":["trace[1722940499] 'agreement among raft nodes before linearized reading' (duration: 1.207671492s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.955957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:30.748058Z","time spent":"1.207891766s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-27T09:59:31.956089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.407941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T09:59:31.956113Z","caller":"traceutil/trace.go:171","msg":"trace[387273977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"786.457811ms","start":"2026-05-27T09:59:31.169649Z","end":"2026-05-27T09:59:31.956107Z","steps":["trace[387273977] 'agreement among raft nodes before linearized reading' (duration: 786.374059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.956093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.725986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T09:59:31.956131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.169634Z","time spent":"786.494073ms","remote":"127.0.0.1:50064","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-05-27T09:59:31.956132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.90565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T09:59:31.956177Z","caller":"traceutil/trace.go:171","msg":"trace[1561720649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"785.966381ms","start":"2026-05-27T09:59:31.170198Z","end":"2026-05-27T09:59:31.956164Z","steps":["trace[1561720649] 'agreement among raft nodes before linearized reading' (duration: 785.851419ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:31.956182Z","caller":"traceutil/trace.go:171","msg":"trace[1166631945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"959.849788ms","start":"2026-05-27T09:59:30.996316Z","end":"2026-05-27T09:59:31.956165Z","steps":["trace[1166631945] 'agreement among raft nodes before linearized reading' (duration: 959.559133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.956216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.170192Z","time spent":"786.015742ms","remote":"127.0.0.1:50064","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-05-27T09:59:31.956242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.942391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T09:59:31.956291Z","caller":"traceutil/trace.go:171","msg":"trace[1950223401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"787.017772ms","start":"2026-05-27T09:59:31.169263Z","end":"2026-05-27T09:59:31.956281Z","steps":["trace[1950223401] 'agreement among raft nodes before linearized reading' (duration: 786.89508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.956297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:30.996294Z","time spent":"959.985161ms","remote":"127.0.0.1:50064","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-05-27T09:59:31.956327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.169245Z","time spent":"787.075573ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T09:59:31.956094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.042458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:31.956428Z","caller":"traceutil/trace.go:171","msg":"trace[1356373966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"353.416906ms","start":"2026-05-27T09:59:31.602999Z","end":"2026-05-27T09:59:31.956416Z","steps":["trace[1356373966] 'agreement among raft nodes before linearized reading' (duration: 353.028709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:31.956471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.602991Z","time spent":"353.470447ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T09:59:32.626368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.346858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:32.626471Z","caller":"traceutil/trace.go:171","msg":"trace[234981997] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"665.077735ms","start":"2026-05-27T09:59:31.961375Z","end":"2026-05-27T09:59:32.626453Z","steps":["trace[234981997] 'process raft request' (duration: 497.568894ms)","trace[234981997] 'compare' (duration: 167.219175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:32.62653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.961359Z","time spent":"665.143706ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:32.682237Z","caller":"traceutil/trace.go:171","msg":"trace[367410529] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"720.085216ms","start":"2026-05-27T09:59:31.962132Z","end":"2026-05-27T09:59:32.682218Z","steps":["trace[367410529] 'process raft request' (duration: 719.618587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:32.68234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.962108Z","time spent":"720.175078ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:32.682453Z","caller":"traceutil/trace.go:171","msg":"trace[1748789974] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"719.256149ms","start":"2026-05-27T09:59:31.963191Z","end":"2026-05-27T09:59:32.682447Z","steps":["trace[1748789974] 'process raft request' (duration: 719.094296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:32.682484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.963182Z","time spent":"719.28747ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:32.682525Z","caller":"traceutil/trace.go:171","msg":"trace[1365167898] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"720.108236ms","start":"2026-05-27T09:59:31.962412Z","end":"2026-05-27T09:59:32.68252Z","steps":["trace[1365167898] 'process raft request' (duration: 719.80126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:32.682548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.962402Z","time spent":"720.132116ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:32.682523Z","caller":"traceutil/trace.go:171","msg":"trace[1882237371] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"719.601315ms","start":"2026-05-27T09:59:31.962881Z","end":"2026-05-27T09:59:32.682482Z","steps":["trace[1882237371] 'process raft request' (duration: 719.373651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:32.682716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.962871Z","time spent":"719.737818ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:32.683014Z","caller":"traceutil/trace.go:171","msg":"trace[1847351907] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"720.095806ms","start":"2026-05-27T09:59:31.962221Z","end":"2026-05-27T09:59:32.682317Z","steps":["trace[1847351907] 'process raft request' (duration: 719.81093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:32.683471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:31.962213Z","time spent":"721.088045ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:33.985799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.82235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:33.985883Z","caller":"traceutil/trace.go:171","msg":"trace[1110124170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"381.958463ms","start":"2026-05-27T09:59:33.603907Z","end":"2026-05-27T09:59:33.985865Z","steps":["trace[1110124170] 'range keys from in-memory index tree' (duration: 381.722798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:33.985931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:33.603876Z","time spent":"382.047364ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T09:59:33.986169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.096519ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:33.986256Z","caller":"traceutil/trace.go:171","msg":"trace[1397518273] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1313; }","duration":"298.187821ms","start":"2026-05-27T09:59:33.688055Z","end":"2026-05-27T09:59:33.986243Z","steps":["trace[1397518273] 'range keys from in-memory index tree' (duration: 298.084059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:41.011855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.525666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:41.011975Z","caller":"traceutil/trace.go:171","msg":"trace[1952088736] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"291.029409ms","start":"2026-05-27T09:59:40.720927Z","end":"2026-05-27T09:59:41.011956Z","steps":["trace[1952088736] 'process raft request' (duration: 90.32624ms)","trace[1952088736] 'compare' (duration: 200.407894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:59:42.627227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118300978s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T09:59:43.030331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521274238s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:59:43.030518Z","caller":"traceutil/trace.go:171","msg":"trace[409381133] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"2.309443753s","start":"2026-05-27T09:59:40.721041Z","end":"2026-05-27T09:59:43.030485Z","steps":["trace[409381133] 'process raft request' (duration: 787.921461ms)","trace[409381133] 'compare' (duration: 1.520960431s)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:43.030606Z","caller":"traceutil/trace.go:171","msg":"trace[431179709] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.566348002s","start":"2026-05-27T09:59:41.464239Z","end":"2026-05-27T09:59:43.030587Z","steps":["trace[431179709] 'process raft request' (duration: 1.566306341s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.030636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:40.721021Z","time spent":"2.309551187s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:43.030652Z","caller":"traceutil/trace.go:171","msg":"trace[146826612] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"2.309015626s","start":"2026-05-27T09:59:40.721601Z","end":"2026-05-27T09:59:43.030616Z","steps":["trace[146826612] 'process raft request' (duration: 2.308826231s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.030686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:41.464214Z","time spent":"1.566437454s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:59:43.030654Z","caller":"traceutil/trace.go:171","msg":"trace[838426105] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1423; }","duration":"2.268676376s","start":"2026-05-27T09:59:40.761958Z","end":"2026-05-27T09:59:43.030635Z","steps":["trace[838426105] 'read index received' (duration: 49.322557ms)","trace[838426105] 'applied index is now lower than readState.Index' (duration: 2.219351599s)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:43.030653Z","caller":"traceutil/trace.go:171","msg":"trace[340786579] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"2.308661089s","start":"2026-05-27T09:59:40.721964Z","end":"2026-05-27T09:59:43.030625Z","steps":["trace[340786579] 'process raft request' (duration: 2.308538406s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.030801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:40.721586Z","time spent":"2.309141259s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:43.030834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:40.721956Z","time spent":"2.308829012s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:43.030889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.26892875s","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-05-27T09:59:43.030951Z","caller":"traceutil/trace.go:171","msg":"trace[847943130] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1374; }","duration":"2.269021863s","start":"2026-05-27T09:59:40.761914Z","end":"2026-05-27T09:59:43.030936Z","steps":["trace[847943130] 'agreement among raft nodes before linearized reading' (duration: 2.268789058s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.031003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:40.761898Z","time spent":"2.269094384s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-27T09:59:43.057891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116105986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:43.057916Z","caller":"traceutil/trace.go:171","msg":"trace[163574593] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.009179517s","start":"2026-05-27T09:59:42.048716Z","end":"2026-05-27T09:59:43.057896Z","steps":["trace[163574593] 'process raft request' (duration: 1.008995953s)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:43.057955Z","caller":"traceutil/trace.go:171","msg":"trace[5213772] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1375; }","duration":"1.116208528s","start":"2026-05-27T09:59:41.941732Z","end":"2026-05-27T09:59:43.057941Z","steps":["trace[5213772] 'agreement among raft nodes before linearized reading' (duration: 1.116099215s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.057992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:41.941713Z","time spent":"1.116268749s","remote":"127.0.0.1:35370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-27T09:59:43.058014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.048693Z","time spent":"1.009266028s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:59:43.058044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.281936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T09:59:43.058091Z","caller":"traceutil/trace.go:171","msg":"trace[347233732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1375; }","duration":"391.362068ms","start":"2026-05-27T09:59:42.666714Z","end":"2026-05-27T09:59:43.058077Z","steps":["trace[347233732] 'agreement among raft nodes before linearized reading' (duration: 391.237106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.057914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.807917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T09:59:43.058239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.726581ms","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:532"} {"level":"info","ts":"2026-05-27T09:59:43.058269Z","caller":"traceutil/trace.go:171","msg":"trace[1304209016] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1375; }","duration":"584.791013ms","start":"2026-05-27T09:59:42.473469Z","end":"2026-05-27T09:59:43.05826Z","steps":["trace[1304209016] 'agreement among raft nodes before linearized reading' (duration: 584.691341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.058296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.473449Z","time spent":"584.840643ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-27T09:59:43.058254Z","caller":"traceutil/trace.go:171","msg":"trace[989867526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"454.191464ms","start":"2026-05-27T09:59:42.604043Z","end":"2026-05-27T09:59:43.058234Z","steps":["trace[989867526] 'agreement among raft nodes before linearized reading' (duration: 453.811047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.058127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.666692Z","time spent":"391.427249ms","remote":"127.0.0.1:50064","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-05-27T09:59:43.058428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.60402Z","time spent":"454.390028ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T09:59:43.058492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006984763s","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:533"} {"level":"info","ts":"2026-05-27T09:59:43.05852Z","caller":"traceutil/trace.go:171","msg":"trace[848367703] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"1.007049315s","start":"2026-05-27T09:59:42.051463Z","end":"2026-05-27T09:59:43.058512Z","steps":["trace[848367703] 'agreement among raft nodes before linearized reading' (duration: 1.006973892s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.058547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.051441Z","time spent":"1.007099256s","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-27T09:59:43.059715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.103284ms","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-05-27T09:59:43.059773Z","caller":"traceutil/trace.go:171","msg":"trace[1445397564] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"339.161323ms","start":"2026-05-27T09:59:42.720598Z","end":"2026-05-27T09:59:43.059759Z","steps":["trace[1445397564] 'agreement among raft nodes before linearized reading' (duration: 338.033972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:43.059813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:59:42.720583Z","time spent":"339.215405ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T10:01:03.60706Z","caller":"traceutil/trace.go:171","msg":"trace[1761205526] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1814; }","duration":"113.78271ms","start":"2026-05-27T10:01:03.493254Z","end":"2026-05-27T10:01:03.607036Z","steps":["trace[1761205526] 'read index received' (duration: 24.986127ms)","trace[1761205526] 'applied index is now lower than readState.Index' (duration: 88.795673ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:01:03.607182Z","caller":"traceutil/trace.go:171","msg":"trace[511375984] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"115.557164ms","start":"2026-05-27T10:01:03.491589Z","end":"2026-05-27T10:01:03.607146Z","steps":["trace[511375984] 'process raft request' (duration: 99.407273ms)","trace[511375984] 'compare' (duration: 15.926237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:01:03.6073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.974664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T10:01:03.607344Z","caller":"traceutil/trace.go:171","msg":"trace[2107709006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1746; }","duration":"114.061335ms","start":"2026-05-27T10:01:03.493268Z","end":"2026-05-27T10:01:03.607329Z","steps":["trace[2107709006] 'agreement among raft nodes before linearized reading' (duration: 113.911382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:01:03.607355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T10:01:03.607423Z","caller":"traceutil/trace.go:171","msg":"trace[1111437790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1746; }","duration":"114.141246ms","start":"2026-05-27T10:01:03.493268Z","end":"2026-05-27T10:01:03.607409Z","steps":["trace[1111437790] 'agreement among raft nodes before linearized reading' (duration: 113.971783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:01:03.607736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.481134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T10:01:03.607769Z","caller":"traceutil/trace.go:171","msg":"trace[2094067026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1746; }","duration":"114.533204ms","start":"2026-05-27T10:01:03.493228Z","end":"2026-05-27T10:01:03.607761Z","steps":["trace[2094067026] 'agreement among raft nodes before linearized reading' (duration: 113.929233ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:01:05.817115Z","caller":"traceutil/trace.go:171","msg":"trace[905212859] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"107.400443ms","start":"2026-05-27T10:01:05.709693Z","end":"2026-05-27T10:01:05.817093Z","steps":["trace[905212859] 'process raft request' (duration: 107.283441ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:01:11.763109Z","caller":"traceutil/trace.go:171","msg":"trace[1926089815] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"106.623587ms","start":"2026-05-27T10:01:11.656461Z","end":"2026-05-27T10:01:11.763084Z","steps":["trace[1926089815] 'process raft request' (duration: 106.429474ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:01:11.768634Z","caller":"traceutil/trace.go:171","msg":"trace[1096634998] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"111.390712ms","start":"2026-05-27T10:01:11.657216Z","end":"2026-05-27T10:01:11.768606Z","steps":["trace[1096634998] 'process raft request' (duration: 111.26945ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:02:04.26709Z","caller":"traceutil/trace.go:171","msg":"trace[1819113371] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"112.814339ms","start":"2026-05-27T10:02:04.154254Z","end":"2026-05-27T10:02:04.267068Z","steps":["trace[1819113371] 'process raft request' (duration: 112.688446ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:02:04.273398Z","caller":"traceutil/trace.go:171","msg":"trace[2100292888] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"119.095004ms","start":"2026-05-27T10:02:04.154276Z","end":"2026-05-27T10:02:04.273371Z","steps":["trace[2100292888] 'process raft request' (duration: 118.976041ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:02:13.741879Z","caller":"traceutil/trace.go:171","msg":"trace[714287893] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"128.841758ms","start":"2026-05-27T10:02:13.613009Z","end":"2026-05-27T10:02:13.74185Z","steps":["trace[714287893] 'process raft request' (duration: 128.659255ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:02:14.296843Z","caller":"traceutil/trace.go:171","msg":"trace[1565262289] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"169.507785ms","start":"2026-05-27T10:02:14.127311Z","end":"2026-05-27T10:02:14.296819Z","steps":["trace[1565262289] 'process raft request' (duration: 169.356832ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:18.315833Z","caller":"traceutil/trace.go:171","msg":"trace[2134629966] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"129.288772ms","start":"2026-05-27T10:03:18.186523Z","end":"2026-05-27T10:03:18.315812Z","steps":["trace[2134629966] 'process raft request' (duration: 129.102028ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:18.89211Z","caller":"traceutil/trace.go:171","msg":"trace[727599315] linearizableReadLoop","detail":"{readStateIndex:2468; appliedIndex:2467; }","duration":"140.79536ms","start":"2026-05-27T10:03:18.751274Z","end":"2026-05-27T10:03:18.89207Z","steps":["trace[727599315] 'read index received' (duration: 140.555974ms)","trace[727599315] 'applied index is now lower than readState.Index' (duration: 237.636µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:03:18.892226Z","caller":"traceutil/trace.go:171","msg":"trace[1931028691] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"150.309809ms","start":"2026-05-27T10:03:18.741864Z","end":"2026-05-27T10:03:18.892174Z","steps":["trace[1931028691] 'process raft request' (duration: 149.91352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:18.89242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.142288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:03:18.892503Z","caller":"traceutil/trace.go:171","msg":"trace[1355349425] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:2370; }","duration":"138.24819ms","start":"2026-05-27T10:03:18.754236Z","end":"2026-05-27T10:03:18.892484Z","steps":["trace[1355349425] 'agreement among raft nodes before linearized reading' (duration: 138.125367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:18.892649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.32175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T10:03:18.892714Z","caller":"traceutil/trace.go:171","msg":"trace[1619707054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2370; }","duration":"141.442583ms","start":"2026-05-27T10:03:18.751257Z","end":"2026-05-27T10:03:18.892699Z","steps":["trace[1619707054] 'agreement among raft nodes before linearized reading' (duration: 141.065945ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:19.106635Z","caller":"traceutil/trace.go:171","msg":"trace[1219714341] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"177.018376ms","start":"2026-05-27T10:03:18.929581Z","end":"2026-05-27T10:03:19.106599Z","steps":["trace[1219714341] 'process raft request' (duration: 155.653363ms)","trace[1219714341] 'compare' (duration: 20.640678ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:03:20.835822Z","caller":"traceutil/trace.go:171","msg":"trace[188204300] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"114.534411ms","start":"2026-05-27T10:03:20.721265Z","end":"2026-05-27T10:03:20.835799Z","steps":["trace[188204300] 'process raft request' (duration: 114.407859ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:21.11634Z","caller":"traceutil/trace.go:171","msg":"trace[1975426877] linearizableReadLoop","detail":"{readStateIndex:2487; appliedIndex:2486; }","duration":"219.611136ms","start":"2026-05-27T10:03:20.896711Z","end":"2026-05-27T10:03:21.116322Z","steps":["trace[1975426877] 'read index received' (duration: 219.458954ms)","trace[1975426877] 'applied index is now lower than readState.Index' (duration: 151.472µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:03:21.116562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.82829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T10:03:21.116602Z","caller":"traceutil/trace.go:171","msg":"trace[2116351521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2388; }","duration":"219.942143ms","start":"2026-05-27T10:03:20.896649Z","end":"2026-05-27T10:03:21.116592Z","steps":["trace[2116351521] 'agreement among raft nodes before linearized reading' (duration: 219.772739ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:21.11642Z","caller":"traceutil/trace.go:171","msg":"trace[1887705458] transaction","detail":"{read_only:false; response_revision:2388; number_of_response:1; }","duration":"224.39083ms","start":"2026-05-27T10:03:20.891994Z","end":"2026-05-27T10:03:21.116385Z","steps":["trace[1887705458] 'process raft request' (duration: 224.198036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:21.116681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.726333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T10:03:21.116796Z","caller":"traceutil/trace.go:171","msg":"trace[1452252016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2388; }","duration":"200.907126ms","start":"2026-05-27T10:03:20.915873Z","end":"2026-05-27T10:03:21.11678Z","steps":["trace[1452252016] 'agreement among raft nodes before linearized reading' (duration: 200.651481ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:21.426844Z","caller":"traceutil/trace.go:171","msg":"trace[106814289] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"201.696552ms","start":"2026-05-27T10:03:21.225118Z","end":"2026-05-27T10:03:21.426815Z","steps":["trace[106814289] 'process raft request' (duration: 116.756165ms)","trace[106814289] 'compare' (duration: 84.533499ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:03:21.426928Z","caller":"traceutil/trace.go:171","msg":"trace[990915074] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"196.148033ms","start":"2026-05-27T10:03:21.23075Z","end":"2026-05-27T10:03:21.426898Z","steps":["trace[990915074] 'process raft request' (duration: 195.810666ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:33.274931Z","caller":"traceutil/trace.go:171","msg":"trace[1450686784] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"373.398632ms","start":"2026-05-27T10:03:32.901508Z","end":"2026-05-27T10:03:33.274907Z","steps":["trace[1450686784] 'process raft request' (duration: 372.922563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:33.275131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:03:32.901469Z","time spent":"373.589056ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:03:40.853399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.96463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T10:03:40.853585Z","caller":"traceutil/trace.go:171","msg":"trace[1575725890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2492; }","duration":"390.205784ms","start":"2026-05-27T10:03:40.463358Z","end":"2026-05-27T10:03:40.853564Z","steps":["trace[1575725890] 'range keys from in-memory index tree' (duration: 389.740065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:40.853663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:03:40.463333Z","time spent":"390.315726ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T10:03:40.85343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.093391ms","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-05-27T10:03:40.853843Z","caller":"traceutil/trace.go:171","msg":"trace[286676282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2492; }","duration":"388.47801ms","start":"2026-05-27T10:03:40.465287Z","end":"2026-05-27T10:03:40.853765Z","steps":["trace[286676282] 'range keys from in-memory index tree' (duration: 387.914799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:40.853975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:03:40.465275Z","time spent":"388.676404ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T10:03:40.853563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.438676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T10:03:40.854276Z","caller":"traceutil/trace.go:171","msg":"trace[798071211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2492; }","duration":"169.228012ms","start":"2026-05-27T10:03:40.685019Z","end":"2026-05-27T10:03:40.854247Z","steps":["trace[798071211] 'range keys from in-memory index tree' (duration: 168.300304ms)"],"step_count":1}