{"level":"warn","ts":"2026-05-19T17:51:27.107717Z","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-19T17:51:27.107799Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99: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.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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-19T17:51:27.107863Z","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-19T17:51:27.107874Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-05-19T17:51:27.107892Z","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-19T17:51:27.108507Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-05-19T17:51:27.108581Z","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.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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-19T17:51:27.11293Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.136259ms"} {"level":"info","ts":"2026-05-19T17:51:27.118811Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-05-19T17:51:27.11888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:51:27.118897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-05-19T17:51:27.118909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:51:27.118917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-05-19T17:51:27.118951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-05-19T17:51:27.130258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:51:27.131743Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:51:27.133036Z","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-19T17:51:27.137282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:51:27.137486Z","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-19T17:51:27.137805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:51:27.137852Z","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-19T17:51:27.137906Z","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-19T17:51:27.138635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-05-19T17:51:27.140477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-05-19T17:51:27.148429Z","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-19T17:51:27.1486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:51:27.148633Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:51:27.148612Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-05-19T17:51:27.148708Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-05-19T17:51:27.519378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:51:27.519449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:51:27.519482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-05-19T17:51:27.519501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:51:27.519513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-05-19T17:51:27.519529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-05-19T17:51:27.519542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-05-19T17:51:27.520846Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:51:27.520865Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:51:27.521098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:51:27.521288Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:51:27.521337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:51:27.521353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:51:27.522574Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:51:27.522796Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:51:27.522906Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:51:27.526073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-05-19T17:51:27.526805Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T17:51:35.938826Z","caller":"traceutil/trace.go:171","msg":"trace[1986268865] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"123.857653ms","start":"2026-05-19T17:51:35.814916Z","end":"2026-05-19T17:51:35.938773Z","steps":["trace[1986268865] 'process raft request' (duration: 103.487579ms)","trace[1986268865] 'compare' (duration: 20.125874ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:36.891149Z","caller":"traceutil/trace.go:171","msg":"trace[124906998] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"289.343078ms","start":"2026-05-19T17:51:36.601763Z","end":"2026-05-19T17:51:36.891106Z","steps":["trace[124906998] 'process raft request' (duration: 289.119899ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:37.144458Z","caller":"traceutil/trace.go:171","msg":"trace[1312793825] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"231.555512ms","start":"2026-05-19T17:51:36.912881Z","end":"2026-05-19T17:51:37.144437Z","steps":["trace[1312793825] 'read index received' (duration: 209.995518ms)","trace[1312793825] 'applied index is now lower than readState.Index' (duration: 21.558824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:37.144657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.753771ms","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-19T17:51:37.144726Z","caller":"traceutil/trace.go:171","msg":"trace[337003437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"231.890767ms","start":"2026-05-19T17:51:36.912817Z","end":"2026-05-19T17:51:37.144708Z","steps":["trace[337003437] 'agreement among raft nodes before linearized reading' (duration: 231.747571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:37.144574Z","caller":"traceutil/trace.go:171","msg":"trace[1815404332] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"243.767192ms","start":"2026-05-19T17:51:36.900752Z","end":"2026-05-19T17:51:37.144519Z","steps":["trace[1815404332] 'process raft request' (duration: 222.136374ms)","trace[1815404332] 'compare' (duration: 21.43896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:38.026916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.238641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-05-19T17:51:38.026985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.874032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-19T17:51:38.02705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.111226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-19T17:51:38.027062Z","caller":"traceutil/trace.go:171","msg":"trace[2122554562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"325.46026ms","start":"2026-05-19T17:51:37.701575Z","end":"2026-05-19T17:51:38.027035Z","steps":["trace[2122554562] 'range keys from in-memory index tree' (duration: 325.085745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.017435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-19T17:51:38.027148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.379036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-19T17:51:38.02718Z","caller":"traceutil/trace.go:171","msg":"trace[1460459148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"276.450979ms","start":"2026-05-19T17:51:37.750719Z","end":"2026-05-19T17:51:38.02717Z","steps":["trace[1460459148] 'range keys from in-memory index tree' (duration: 276.281742ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:38.02717Z","caller":"traceutil/trace.go:171","msg":"trace[613868278] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:344; }","duration":"577.065637ms","start":"2026-05-19T17:51:37.450097Z","end":"2026-05-19T17:51:38.027163Z","steps":["trace[613868278] 'range keys from in-memory index tree' (duration: 576.961543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.45008Z","time spent":"577.171431ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.027461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.405175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T17:51:38.027484Z","caller":"traceutil/trace.go:171","msg":"trace[314189548] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"526.466937ms","start":"2026-05-19T17:51:37.501011Z","end":"2026-05-19T17:51:38.027478Z","steps":["trace[314189548] 'range keys from in-memory index tree' (duration: 526.227417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.500986Z","time spent":"526.51359ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.027178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.701545Z","time spent":"325.620207ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.027552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.428851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T17:51:38.027605Z","caller":"traceutil/trace.go:171","msg":"trace[275952898] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:344; }","duration":"385.526265ms","start":"2026-05-19T17:51:37.642065Z","end":"2026-05-19T17:51:38.027591Z","steps":["trace[275952898] 'range keys from in-memory index tree' (duration: 385.307466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.64204Z","time spent":"385.586677ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-19T17:51:38.027094Z","caller":"traceutil/trace.go:171","msg":"trace[5045190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"226.098482ms","start":"2026-05-19T17:51:37.800973Z","end":"2026-05-19T17:51:38.027072Z","steps":["trace[5045190] 'range keys from in-memory index tree' (duration: 225.714844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.639824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-19T17:51:38.027115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.555925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-19T17:51:38.027786Z","caller":"traceutil/trace.go:171","msg":"trace[1943917781] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"376.715717ms","start":"2026-05-19T17:51:37.651044Z","end":"2026-05-19T17:51:38.027759Z","steps":["trace[1943917781] 'range keys from in-memory index tree' (duration: 376.53732ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:38.027797Z","caller":"traceutil/trace.go:171","msg":"trace[1137632085] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"626.299646ms","start":"2026-05-19T17:51:37.401488Z","end":"2026-05-19T17:51:38.027788Z","steps":["trace[1137632085] 'range keys from in-memory index tree' (duration: 625.44849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.401461Z","time spent":"626.355038ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.027823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.651022Z","time spent":"376.79206ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-19T17:51:38.027085Z","caller":"traceutil/trace.go:171","msg":"trace[607677860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"475.182568ms","start":"2026-05-19T17:51:37.551893Z","end":"2026-05-19T17:51:38.027076Z","steps":["trace[607677860] 'range keys from in-memory index tree' (duration: 475.039933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.027892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.551875Z","time spent":"475.985682ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.027393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.850464ms","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-19T17:51:38.02808Z","caller":"traceutil/trace.go:171","msg":"trace[1918368434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"426.526783ms","start":"2026-05-19T17:51:37.601532Z","end":"2026-05-19T17:51:38.028058Z","steps":["trace[1918368434] 'range keys from in-memory index tree' (duration: 425.742989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:38.028148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:37.601449Z","time spent":"426.68281ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-19T17:51:38.870913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.575517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-05-19T17:51:38.871031Z","caller":"traceutil/trace.go:171","msg":"trace[646848969] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:391; }","duration":"165.763055ms","start":"2026-05-19T17:51:38.705247Z","end":"2026-05-19T17:51:38.87101Z","steps":["trace[646848969] 'range keys from in-memory index tree' (duration: 165.434571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:39.554429Z","caller":"traceutil/trace.go:171","msg":"trace[1273426134] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"140.416923ms","start":"2026-05-19T17:51:39.413973Z","end":"2026-05-19T17:51:39.55439Z","steps":["trace[1273426134] 'process raft request' (duration: 140.202624ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:41.358915Z","caller":"traceutil/trace.go:171","msg":"trace[1868981446] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"127.117346ms","start":"2026-05-19T17:51:41.231767Z","end":"2026-05-19T17:51:41.358885Z","steps":["trace[1868981446] 'process raft request' (duration: 49.473937ms)","trace[1868981446] 'compare' (duration: 77.504204ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:42.725326Z","caller":"traceutil/trace.go:171","msg":"trace[2140195219] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"369.513364ms","start":"2026-05-19T17:51:42.355795Z","end":"2026-05-19T17:51:42.725308Z","steps":["trace[2140195219] 'process raft request' (duration: 369.40489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:42.727286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:42.355782Z","time spent":"369.599807ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:43.067276Z","caller":"traceutil/trace.go:171","msg":"trace[1641226378] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"304.672479ms","start":"2026-05-19T17:51:42.762583Z","end":"2026-05-19T17:51:43.067255Z","steps":["trace[1641226378] 'process raft request' (duration: 304.628058ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:43.06732Z","caller":"traceutil/trace.go:171","msg":"trace[1086868582] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"317.992128ms","start":"2026-05-19T17:51:42.749283Z","end":"2026-05-19T17:51:43.067275Z","steps":["trace[1086868582] 'process raft request' (duration: 240.918876ms)","trace[1086868582] 'compare' (duration: 76.890955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:43.067415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:42.762543Z","time spent":"304.805064ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:43.067481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:42.749259Z","time spent":"318.141424ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:43.566739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.663646ms","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-19T17:51:43.566838Z","caller":"traceutil/trace.go:171","msg":"trace[1123628840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"203.813151ms","start":"2026-05-19T17:51:43.363002Z","end":"2026-05-19T17:51:43.566815Z","steps":["trace[1123628840] 'range keys from in-memory index tree' (duration: 203.550572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:43.566908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.467424ms","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-19T17:51:43.567019Z","caller":"traceutil/trace.go:171","msg":"trace[492757242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"342.637061ms","start":"2026-05-19T17:51:43.224355Z","end":"2026-05-19T17:51:43.566992Z","steps":["trace[492757242] 'range keys from in-memory index tree' (duration: 342.267066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:43.567078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:43.224327Z","time spent":"342.739185ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:51:44.241312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.13919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:51:44.241557Z","caller":"traceutil/trace.go:171","msg":"trace[1037524458] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"665.684351ms","start":"2026-05-19T17:51:43.575856Z","end":"2026-05-19T17:51:44.24154Z","steps":["trace[1037524458] 'read index received' (duration: 5.805553ms)","trace[1037524458] 'applied index is now lower than readState.Index' (duration: 659.877788ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:44.241661Z","caller":"traceutil/trace.go:171","msg":"trace[1401879888] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"663.192121ms","start":"2026-05-19T17:51:43.578459Z","end":"2026-05-19T17:51:44.241651Z","steps":["trace[1401879888] 'process raft request' (duration: 662.969652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:44.241735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:43.578449Z","time spent":"663.252654ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:44.241902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.616125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:51:44.241937Z","caller":"traceutil/trace.go:171","msg":"trace[619334529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"173.697408ms","start":"2026-05-19T17:51:44.06823Z","end":"2026-05-19T17:51:44.241928Z","steps":["trace[619334529] 'agreement among raft nodes before linearized reading' (duration: 173.629446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:44.241941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.044406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:44.242068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.613205ms","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-19T17:51:44.241568Z","caller":"traceutil/trace.go:171","msg":"trace[1464915546] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"668.078547ms","start":"2026-05-19T17:51:43.573436Z","end":"2026-05-19T17:51:44.241514Z","steps":["trace[1464915546] 'process raft request' (duration: 481.472528ms)","trace[1464915546] 'compare' (duration: 185.663912ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:44.242217Z","caller":"traceutil/trace.go:171","msg":"trace[1081148288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"666.249634ms","start":"2026-05-19T17:51:43.575848Z","end":"2026-05-19T17:51:44.242097Z","steps":["trace[1081148288] 'agreement among raft nodes before linearized reading' (duration: 665.919251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:44.242323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:43.573417Z","time spent":"668.8759ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:44.242462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:43.575806Z","time spent":"666.553196ms","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:51:44.242186Z","caller":"traceutil/trace.go:171","msg":"trace[527187238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"588.862654ms","start":"2026-05-19T17:51:43.653297Z","end":"2026-05-19T17:51:44.242159Z","steps":["trace[527187238] 'agreement among raft nodes before linearized reading' (duration: 588.475369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:44.242626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:43.653285Z","time spent":"589.330233ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:51:44.569653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.287724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:51:44.57056Z","caller":"traceutil/trace.go:171","msg":"trace[16430376] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"323.73364ms","start":"2026-05-19T17:51:44.246766Z","end":"2026-05-19T17:51:44.5705Z","steps":["trace[16430376] 'process raft request' (duration: 128.512729ms)","trace[16430376] 'compare' (duration: 194.111967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:44.570656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:44.24675Z","time spent":"323.863725ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:44.606904Z","caller":"traceutil/trace.go:171","msg":"trace[172681555] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"359.927723ms","start":"2026-05-19T17:51:44.246891Z","end":"2026-05-19T17:51:44.606819Z","steps":["trace[172681555] 'process raft request' (duration: 359.701244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:44.607144Z","caller":"traceutil/trace.go:171","msg":"trace[1887222561] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"353.102872ms","start":"2026-05-19T17:51:44.25397Z","end":"2026-05-19T17:51:44.607073Z","steps":["trace[1887222561] 'process raft request' (duration: 352.757089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:44.607185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:44.246857Z","time spent":"360.195863ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:44.607484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:44.253943Z","time spent":"353.275099ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:46.959836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.1329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T17:51:46.959931Z","caller":"traceutil/trace.go:171","msg":"trace[1410165214] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"712.457542ms","start":"2026-05-19T17:51:46.24746Z","end":"2026-05-19T17:51:46.959917Z","steps":["trace[1410165214] 'read index received' (duration: 275.156426ms)","trace[1410165214] 'applied index is now lower than readState.Index' (duration: 437.299576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:46.960046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.50822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:46.960078Z","caller":"traceutil/trace.go:171","msg":"trace[1379744079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"409.565692ms","start":"2026-05-19T17:51:46.550501Z","end":"2026-05-19T17:51:46.960067Z","steps":["trace[1379744079] 'agreement among raft nodes before linearized reading' (duration: 409.494909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:46.960107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:46.550487Z","time spent":"409.613673ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:51:46.960125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.653849ms","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-19T17:51:46.960125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.616863ms","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-19T17:51:46.960223Z","caller":"traceutil/trace.go:171","msg":"trace[1056030770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"347.751608ms","start":"2026-05-19T17:51:46.612435Z","end":"2026-05-19T17:51:46.960187Z","steps":["trace[1056030770] 'agreement among raft nodes before linearized reading' (duration: 347.592612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:46.960179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.515161ms","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-19T17:51:46.960275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:46.61241Z","time spent":"347.855902ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:51:46.960286Z","caller":"traceutil/trace.go:171","msg":"trace[1310152520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"385.660447ms","start":"2026-05-19T17:51:46.574608Z","end":"2026-05-19T17:51:46.960269Z","steps":["trace[1310152520] 'agreement among raft nodes before linearized reading' (duration: 385.496131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:46.960351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:46.57459Z","time spent":"385.74734ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:51:46.960224Z","caller":"traceutil/trace.go:171","msg":"trace[2040544957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"712.770064ms","start":"2026-05-19T17:51:46.247409Z","end":"2026-05-19T17:51:46.960179Z","steps":["trace[2040544957] 'agreement among raft nodes before linearized reading' (duration: 712.541776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:46.960417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:46.247388Z","time spent":"713.012114ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:51:47.086534Z","caller":"traceutil/trace.go:171","msg":"trace[281680073] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"120.910988ms","start":"2026-05-19T17:51:46.965596Z","end":"2026-05-19T17:51:47.086507Z","steps":["trace[281680073] 'process raft request' (duration: 120.201031ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:47.086531Z","caller":"traceutil/trace.go:171","msg":"trace[1739255273] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"120.850195ms","start":"2026-05-19T17:51:46.965658Z","end":"2026-05-19T17:51:47.086509Z","steps":["trace[1739255273] 'process raft request' (duration: 120.71477ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:55.277371Z","caller":"traceutil/trace.go:171","msg":"trace[169126696] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"156.199148ms","start":"2026-05-19T17:51:55.121143Z","end":"2026-05-19T17:51:55.277342Z","steps":["trace[169126696] 'read index received' (duration: 155.96142ms)","trace[169126696] 'applied index is now lower than readState.Index' (duration: 236.758µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:55.277417Z","caller":"traceutil/trace.go:171","msg":"trace[758505481] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"191.79859ms","start":"2026-05-19T17:51:55.085581Z","end":"2026-05-19T17:51:55.27738Z","steps":["trace[758505481] 'process raft request' (duration: 191.594402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:55.277748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.50296ms","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-19T17:51:55.277799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.628754ms","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-19T17:51:55.277852Z","caller":"traceutil/trace.go:171","msg":"trace[188691768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"156.711506ms","start":"2026-05-19T17:51:55.121116Z","end":"2026-05-19T17:51:55.277828Z","steps":["trace[188691768] 'agreement among raft nodes before linearized reading' (duration: 156.277561ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:55.277868Z","caller":"traceutil/trace.go:171","msg":"trace[1146996200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"156.757748ms","start":"2026-05-19T17:51:55.121091Z","end":"2026-05-19T17:51:55.277849Z","steps":["trace[1146996200] 'agreement among raft nodes before linearized reading' (duration: 156.404396ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:57.484031Z","caller":"traceutil/trace.go:171","msg":"trace[202986906] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"184.701455ms","start":"2026-05-19T17:51:57.29931Z","end":"2026-05-19T17:51:57.484011Z","steps":["trace[202986906] 'read index received' (duration: 184.56357ms)","trace[202986906] 'applied index is now lower than readState.Index' (duration: 137.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:57.484125Z","caller":"traceutil/trace.go:171","msg":"trace[2041013286] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"198.730247ms","start":"2026-05-19T17:51:57.285353Z","end":"2026-05-19T17:51:57.484084Z","steps":["trace[2041013286] 'process raft request' (duration: 198.461417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:57.484188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.85695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:51:57.484246Z","caller":"traceutil/trace.go:171","msg":"trace[912926618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"184.956134ms","start":"2026-05-19T17:51:57.299283Z","end":"2026-05-19T17:51:57.484239Z","steps":["trace[912926618] 'agreement among raft nodes before linearized reading' (duration: 184.85083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:57.484386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.967064ms","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-19T17:51:57.484534Z","caller":"traceutil/trace.go:171","msg":"trace[694806611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"185.16185ms","start":"2026-05-19T17:51:57.299337Z","end":"2026-05-19T17:51:57.484499Z","steps":["trace[694806611] 'agreement among raft nodes before linearized reading' (duration: 184.933033ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:57.976149Z","caller":"traceutil/trace.go:171","msg":"trace[1324702039] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"240.129311ms","start":"2026-05-19T17:51:57.735988Z","end":"2026-05-19T17:51:57.976118Z","steps":["trace[1324702039] 'process raft request' (duration: 239.951505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:02.198389Z","caller":"traceutil/trace.go:171","msg":"trace[918659194] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"204.412003ms","start":"2026-05-19T17:52:01.993952Z","end":"2026-05-19T17:52:02.198364Z","steps":["trace[918659194] 'process raft request' (duration: 204.292059ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:03.851236Z","caller":"traceutil/trace.go:171","msg":"trace[620169515] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"255.784523ms","start":"2026-05-19T17:52:03.595426Z","end":"2026-05-19T17:52:03.851211Z","steps":["trace[620169515] 'process raft request' (duration: 255.642628ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:03.858375Z","caller":"traceutil/trace.go:171","msg":"trace[892524265] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"261.615111ms","start":"2026-05-19T17:52:03.596678Z","end":"2026-05-19T17:52:03.858294Z","steps":["trace[892524265] 'process raft request' (duration: 261.427145ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:03.858544Z","caller":"traceutil/trace.go:171","msg":"trace[244090334] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:500; }","duration":"107.383193ms","start":"2026-05-19T17:52:03.751143Z","end":"2026-05-19T17:52:03.858527Z","steps":["trace[244090334] 'read index received' (duration: 100.041005ms)","trace[244090334] 'applied index is now lower than readState.Index' (duration: 7.341168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:03.858662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.514857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:03.858699Z","caller":"traceutil/trace.go:171","msg":"trace[617934155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:485; }","duration":"107.637791ms","start":"2026-05-19T17:52:03.751051Z","end":"2026-05-19T17:52:03.858689Z","steps":["trace[617934155] 'agreement among raft nodes before linearized reading' (duration: 107.539788ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:03.858865Z","caller":"traceutil/trace.go:171","msg":"trace[708949790] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"261.845688ms","start":"2026-05-19T17:52:03.597009Z","end":"2026-05-19T17:52:03.858854Z","steps":["trace[708949790] 'process raft request' (duration: 261.247599ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:04.109386Z","caller":"traceutil/trace.go:171","msg":"trace[1145387567] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"137.979949ms","start":"2026-05-19T17:52:03.971357Z","end":"2026-05-19T17:52:04.109337Z","steps":["trace[1145387567] 'process raft request' (duration: 132.737671ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:04.588133Z","caller":"traceutil/trace.go:171","msg":"trace[261121119] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"104.802186ms","start":"2026-05-19T17:52:04.483302Z","end":"2026-05-19T17:52:04.588104Z","steps":["trace[261121119] 'process raft request' (duration: 49.240737ms)","trace[261121119] 'compare' (duration: 55.335871ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:04.588415Z","caller":"traceutil/trace.go:171","msg":"trace[1627861688] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"103.97618ms","start":"2026-05-19T17:52:04.484424Z","end":"2026-05-19T17:52:04.5884Z","steps":["trace[1627861688] 'process raft request' (duration: 103.610037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:04.588539Z","caller":"traceutil/trace.go:171","msg":"trace[1996526037] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:532; }","duration":"101.376097ms","start":"2026-05-19T17:52:04.487153Z","end":"2026-05-19T17:52:04.588529Z","steps":["trace[1996526037] 'read index received' (duration: 45.367943ms)","trace[1996526037] 'applied index is now lower than readState.Index' (duration: 56.007054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:04.588784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.614604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-05-19T17:52:04.588825Z","caller":"traceutil/trace.go:171","msg":"trace[363724220] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:517; }","duration":"101.710538ms","start":"2026-05-19T17:52:04.487103Z","end":"2026-05-19T17:52:04.588814Z","steps":["trace[363724220] 'agreement among raft nodes before linearized reading' (duration: 101.565993ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:04.96323Z","caller":"traceutil/trace.go:171","msg":"trace[982107679] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"308.221919ms","start":"2026-05-19T17:52:04.654934Z","end":"2026-05-19T17:52:04.963156Z","steps":["trace[982107679] 'read index received' (duration: 241.449982ms)","trace[982107679] 'applied index is now lower than readState.Index' (duration: 66.770747ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:04.963428Z","caller":"traceutil/trace.go:171","msg":"trace[1190131842] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"359.692946ms","start":"2026-05-19T17:52:04.603694Z","end":"2026-05-19T17:52:04.963387Z","steps":["trace[1190131842] 'process raft request' (duration: 292.748874ms)","trace[1190131842] 'compare' (duration: 66.192028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:04.963491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.548019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-05-19T17:52:04.96353Z","caller":"traceutil/trace.go:171","msg":"trace[1923281238] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:518; }","duration":"308.636601ms","start":"2026-05-19T17:52:04.654882Z","end":"2026-05-19T17:52:04.963518Z","steps":["trace[1923281238] 'agreement among raft nodes before linearized reading' (duration: 308.483766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:04.963564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:04.654859Z","time spent":"308.697173ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":542,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-05-19T17:52:04.963611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:04.603671Z","time spent":"359.839811ms","remote":"127.0.0.1:51276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4656,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:04.963759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.057771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-05-19T17:52:04.963839Z","caller":"traceutil/trace.go:171","msg":"trace[1628687207] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:518; }","duration":"252.190015ms","start":"2026-05-19T17:52:04.711625Z","end":"2026-05-19T17:52:04.963815Z","steps":["trace[1628687207] 'agreement among raft nodes before linearized reading' (duration: 251.786351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:04.964314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.577429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-19T17:52:04.964402Z","caller":"traceutil/trace.go:171","msg":"trace[1383341620] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:518; }","duration":"208.704863ms","start":"2026-05-19T17:52:04.755677Z","end":"2026-05-19T17:52:04.964382Z","steps":["trace[1383341620] 'agreement among raft nodes before linearized reading' (duration: 207.768692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.382475Z","caller":"traceutil/trace.go:171","msg":"trace[936300181] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"426.748718ms","start":"2026-05-19T17:52:05.955686Z","end":"2026-05-19T17:52:06.382435Z","steps":["trace[936300181] 'process raft request' (duration: 426.545082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.382658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:05.955662Z","time spent":"426.906413ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:06.407487Z","caller":"traceutil/trace.go:171","msg":"trace[527832989] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"341.355573ms","start":"2026-05-19T17:52:06.066095Z","end":"2026-05-19T17:52:06.40745Z","steps":["trace[527832989] 'read index received' (duration: 316.07421ms)","trace[527832989] 'applied index is now lower than readState.Index' (duration: 25.279543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:06.407633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.527398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:06.4077Z","caller":"traceutil/trace.go:171","msg":"trace[1666437510] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:521; }","duration":"341.599951ms","start":"2026-05-19T17:52:06.066084Z","end":"2026-05-19T17:52:06.407684Z","steps":["trace[1666437510] 'agreement among raft nodes before linearized reading' (duration: 341.496507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.407739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.066044Z","time spent":"341.686903ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-19T17:52:06.407866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.978965ms","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-19T17:52:06.407941Z","caller":"traceutil/trace.go:171","msg":"trace[335658494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"185.107349ms","start":"2026-05-19T17:52:06.222814Z","end":"2026-05-19T17:52:06.407921Z","steps":["trace[335658494] 'agreement among raft nodes before linearized reading' (duration: 184.949264ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.731431Z","caller":"traceutil/trace.go:171","msg":"trace[1991209570] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"149.281386ms","start":"2026-05-19T17:52:06.582125Z","end":"2026-05-19T17:52:06.731406Z","steps":["trace[1991209570] 'process raft request' (duration: 149.11784ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.975638Z","caller":"traceutil/trace.go:171","msg":"trace[1788799021] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"343.291594ms","start":"2026-05-19T17:52:06.63232Z","end":"2026-05-19T17:52:06.975611Z","steps":["trace[1788799021] 'process raft request' (duration: 343.080517ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.97574Z","caller":"traceutil/trace.go:171","msg":"trace[2007956964] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"343.318615ms","start":"2026-05-19T17:52:06.632381Z","end":"2026-05-19T17:52:06.975699Z","steps":["trace[2007956964] 'process raft request' (duration: 343.072928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.975799Z","caller":"traceutil/trace.go:171","msg":"trace[461942043] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"335.052996ms","start":"2026-05-19T17:52:06.640734Z","end":"2026-05-19T17:52:06.975787Z","steps":["trace[461942043] 'process raft request' (duration: 334.908762ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.97562Z","caller":"traceutil/trace.go:171","msg":"trace[622184204] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"353.566357ms","start":"2026-05-19T17:52:06.622011Z","end":"2026-05-19T17:52:06.975577Z","steps":["trace[622184204] 'process raft request' (duration: 353.242826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.975932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.63235Z","time spent":"343.486081ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:06.975897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.64073Z","time spent":"335.107738ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:06.975944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.621982Z","time spent":"353.888487ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.975742Z","caller":"traceutil/trace.go:171","msg":"trace[589130611] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"335.034455ms","start":"2026-05-19T17:52:06.64068Z","end":"2026-05-19T17:52:06.975714Z","steps":["trace[589130611] 'process raft request' (duration: 334.912641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.976427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.640667Z","time spent":"335.50689ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:06.976529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.632298Z","time spent":"343.453939ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.976655Z","caller":"traceutil/trace.go:171","msg":"trace[74952994] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"335.83647ms","start":"2026-05-19T17:52:06.640794Z","end":"2026-05-19T17:52:06.97663Z","steps":["trace[74952994] 'process raft request' (duration: 335.616363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.976714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.64079Z","time spent":"335.897422ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.976974Z","caller":"traceutil/trace.go:171","msg":"trace[557376232] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"334.887929ms","start":"2026-05-19T17:52:06.642067Z","end":"2026-05-19T17:52:06.976955Z","steps":["trace[557376232] 'process raft request' (duration: 334.58971ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:06.977003Z","caller":"traceutil/trace.go:171","msg":"trace[278621478] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"335.24512ms","start":"2026-05-19T17:52:06.641743Z","end":"2026-05-19T17:52:06.976988Z","steps":["trace[278621478] 'process raft request' (duration: 334.833538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.977062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.641736Z","time spent":"335.291303ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.977158Z","caller":"traceutil/trace.go:171","msg":"trace[1674294666] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"312.311601ms","start":"2026-05-19T17:52:06.664836Z","end":"2026-05-19T17:52:06.977147Z","steps":["trace[1674294666] 'process raft request' (duration: 311.888708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.977156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.642047Z","time spent":"334.972612ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:06.977206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.66481Z","time spent":"312.376783ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.97721Z","caller":"traceutil/trace.go:171","msg":"trace[672541673] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"310.118942ms","start":"2026-05-19T17:52:06.667079Z","end":"2026-05-19T17:52:06.977198Z","steps":["trace[672541673] 'process raft request' (duration: 309.717099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:06.977304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.667072Z","time spent":"310.190424ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:06.97715Z","caller":"traceutil/trace.go:171","msg":"trace[848590928] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"282.036941ms","start":"2026-05-19T17:52:06.695089Z","end":"2026-05-19T17:52:06.977126Z","steps":["trace[848590928] 'process raft request' (duration: 281.852745ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.421701Z","caller":"traceutil/trace.go:171","msg":"trace[632539679] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"608.036663ms","start":"2026-05-19T17:52:06.813636Z","end":"2026-05-19T17:52:07.421673Z","steps":["trace[632539679] 'process raft request' (duration: 607.828877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.421845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.813597Z","time spent":"608.186367ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:07.663897Z","caller":"traceutil/trace.go:171","msg":"trace[671593012] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"674.046169ms","start":"2026-05-19T17:52:06.989801Z","end":"2026-05-19T17:52:07.663847Z","steps":["trace[671593012] 'process raft request' (duration: 610.470094ms)","trace[671593012] 'compare' (duration: 63.270195ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:07.66393Z","caller":"traceutil/trace.go:171","msg":"trace[1661785140] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"672.883223ms","start":"2026-05-19T17:52:06.991014Z","end":"2026-05-19T17:52:07.663897Z","steps":["trace[1661785140] 'process raft request' (duration: 672.823701ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.663939Z","caller":"traceutil/trace.go:171","msg":"trace[1398307035] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"673.402668ms","start":"2026-05-19T17:52:06.990508Z","end":"2026-05-19T17:52:07.663911Z","steps":["trace[1398307035] 'process raft request' (duration: 673.303165ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.66398Z","caller":"traceutil/trace.go:171","msg":"trace[376661277] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"673.622846ms","start":"2026-05-19T17:52:06.990329Z","end":"2026-05-19T17:52:07.663952Z","steps":["trace[376661277] 'process raft request' (duration: 673.361878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.664059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.989779Z","time spent":"674.200724ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:07.664097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.990321Z","time spent":"673.710858ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:07.664149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.990498Z","time spent":"673.512943ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:07.664221Z","caller":"traceutil/trace.go:171","msg":"trace[1817018448] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"671.264563ms","start":"2026-05-19T17:52:06.99291Z","end":"2026-05-19T17:52:07.664175Z","steps":["trace[1817018448] 'process raft request' (duration: 671.168689ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.664367Z","caller":"traceutil/trace.go:171","msg":"trace[1163373627] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"661.424404ms","start":"2026-05-19T17:52:07.002929Z","end":"2026-05-19T17:52:07.664354Z","steps":["trace[1163373627] 'process raft request' (duration: 661.374543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.664335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.992903Z","time spent":"671.362385ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:07.664368Z","caller":"traceutil/trace.go:171","msg":"trace[158308214] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"672.127639ms","start":"2026-05-19T17:52:06.992219Z","end":"2026-05-19T17:52:07.664346Z","steps":["trace[158308214] 'process raft request' (duration: 671.654263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.664471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:07.002918Z","time spent":"661.480215ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:07.664511Z","caller":"traceutil/trace.go:171","msg":"trace[779220680] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"667.584128ms","start":"2026-05-19T17:52:06.996913Z","end":"2026-05-19T17:52:07.664498Z","steps":["trace[779220680] 'process raft request' (duration: 667.226897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.664605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.996903Z","time spent":"667.66339ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:07.664635Z","caller":"traceutil/trace.go:171","msg":"trace[717751769] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"669.087604ms","start":"2026-05-19T17:52:06.99554Z","end":"2026-05-19T17:52:07.664628Z","steps":["trace[717751769] 'process raft request' (duration: 668.576627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.66467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.995531Z","time spent":"669.120884ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:07.664674Z","caller":"traceutil/trace.go:171","msg":"trace[414474584] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"665.537003ms","start":"2026-05-19T17:52:06.999099Z","end":"2026-05-19T17:52:07.664636Z","steps":["trace[414474584] 'process raft request' (duration: 665.160561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.664791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.999071Z","time spent":"665.653517ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:07.664814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.992194Z","time spent":"672.226802ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:07.665146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.991004Z","time spent":"674.033019ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:07.681988Z","caller":"traceutil/trace.go:171","msg":"trace[1873557641] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"667.631078ms","start":"2026-05-19T17:52:06.997027Z","end":"2026-05-19T17:52:07.664658Z","steps":["trace[1873557641] 'process raft request' (duration: 667.154804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.682317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:06.996994Z","time spent":"685.121892ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:07.694149Z","caller":"traceutil/trace.go:171","msg":"trace[366286039] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:554; }","duration":"141.381343ms","start":"2026-05-19T17:52:07.552753Z","end":"2026-05-19T17:52:07.694134Z","steps":["trace[366286039] 'read index received' (duration: 47.544467ms)","trace[366286039] 'applied index is now lower than readState.Index' (duration: 93.836296ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:07.694224Z","caller":"traceutil/trace.go:171","msg":"trace[862498804] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"141.062863ms","start":"2026-05-19T17:52:07.553154Z","end":"2026-05-19T17:52:07.694217Z","steps":["trace[862498804] 'process raft request' (duration: 140.94742ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.694217Z","caller":"traceutil/trace.go:171","msg":"trace[666261400] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"205.458874ms","start":"2026-05-19T17:52:07.488732Z","end":"2026-05-19T17:52:07.694191Z","steps":["trace[666261400] 'process raft request' (duration: 205.297019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.694489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.716833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.694561Z","caller":"traceutil/trace.go:171","msg":"trace[1335830912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"141.833826ms","start":"2026-05-19T17:52:07.552712Z","end":"2026-05-19T17:52:07.694545Z","steps":["trace[1335830912] 'agreement among raft nodes before linearized reading' (duration: 141.63004ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.991032Z","caller":"traceutil/trace.go:171","msg":"trace[567605649] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"141.539018ms","start":"2026-05-19T17:52:07.849455Z","end":"2026-05-19T17:52:07.990994Z","steps":["trace[567605649] 'process raft request' (duration: 132.392744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.991114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.181581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.991155Z","caller":"traceutil/trace.go:171","msg":"trace[1732989633] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:565; }","duration":"145.260994ms","start":"2026-05-19T17:52:07.845882Z","end":"2026-05-19T17:52:07.991143Z","steps":["trace[1732989633] 'agreement among raft nodes before linearized reading' (duration: 145.145541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.991364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.879483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.991461Z","caller":"traceutil/trace.go:171","msg":"trace[355948059] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:565; }","duration":"146.003137ms","start":"2026-05-19T17:52:07.845433Z","end":"2026-05-19T17:52:07.991436Z","steps":["trace[355948059] 'agreement among raft nodes before linearized reading' (duration: 145.790221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.990991Z","caller":"traceutil/trace.go:171","msg":"trace[524905024] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"136.547524ms","start":"2026-05-19T17:52:07.854416Z","end":"2026-05-19T17:52:07.990963Z","steps":["trace[524905024] 'read index received' (duration: 127.527953ms)","trace[524905024] 'applied index is now lower than readState.Index' (duration: 9.018631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:07.994951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.074176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.995022Z","caller":"traceutil/trace.go:171","msg":"trace[14280754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:567; }","duration":"124.17074ms","start":"2026-05-19T17:52:07.870834Z","end":"2026-05-19T17:52:07.995005Z","steps":["trace[14280754] 'agreement among raft nodes before linearized reading' (duration: 124.049906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.995241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.093409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.995297Z","caller":"traceutil/trace.go:171","msg":"trace[1103697839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:567; }","duration":"108.185542ms","start":"2026-05-19T17:52:07.887098Z","end":"2026-05-19T17:52:07.995284Z","steps":["trace[1103697839] 'agreement among raft nodes before linearized reading' (duration: 108.088199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.995308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.995412Z","caller":"traceutil/trace.go:171","msg":"trace[1494507189] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"128.536474ms","start":"2026-05-19T17:52:07.866857Z","end":"2026-05-19T17:52:07.995394Z","steps":["trace[1494507189] 'process raft request' (duration: 127.729799ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.995412Z","caller":"traceutil/trace.go:171","msg":"trace[989559412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:567; }","duration":"123.630842ms","start":"2026-05-19T17:52:07.871706Z","end":"2026-05-19T17:52:07.995337Z","steps":["trace[989559412] 'agreement among raft nodes before linearized reading' (duration: 123.56849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.995467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.479638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.995604Z","caller":"traceutil/trace.go:171","msg":"trace[561542438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:567; }","duration":"124.641354ms","start":"2026-05-19T17:52:07.870952Z","end":"2026-05-19T17:52:07.995594Z","steps":["trace[561542438] 'agreement among raft nodes before linearized reading' (duration: 124.487929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.995638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.819211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.995684Z","caller":"traceutil/trace.go:171","msg":"trace[1378917335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:567; }","duration":"107.869122ms","start":"2026-05-19T17:52:07.887804Z","end":"2026-05-19T17:52:07.995673Z","steps":["trace[1378917335] 'agreement among raft nodes before linearized reading' (duration: 107.79593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:07.995844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.665767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:07.99589Z","caller":"traceutil/trace.go:171","msg":"trace[1112207375] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:567; }","duration":"108.724468ms","start":"2026-05-19T17:52:07.887152Z","end":"2026-05-19T17:52:07.995876Z","steps":["trace[1112207375] 'agreement among raft nodes before linearized reading' (duration: 108.664936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:08.42096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.724969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:52:08.421424Z","caller":"traceutil/trace.go:171","msg":"trace[1953784222] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"220.524075ms","start":"2026-05-19T17:52:08.200844Z","end":"2026-05-19T17:52:08.421369Z","steps":["trace[1953784222] 'process raft request' (duration: 28.28876ms)","trace[1953784222] 'compare' (duration: 191.468022ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:08.7668Z","caller":"traceutil/trace.go:171","msg":"trace[1131189256] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"102.839744ms","start":"2026-05-19T17:52:08.663932Z","end":"2026-05-19T17:52:08.766772Z","steps":["trace[1131189256] 'process raft request' (duration: 97.978574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:09.681844Z","caller":"traceutil/trace.go:171","msg":"trace[1419407917] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"896.029987ms","start":"2026-05-19T17:52:08.785786Z","end":"2026-05-19T17:52:09.681816Z","steps":["trace[1419407917] 'process raft request' (duration: 895.875412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:09.682037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:08.78576Z","time spent":"896.180801ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:11.164739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.292812ms","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-19T17:52:11.164892Z","caller":"traceutil/trace.go:171","msg":"trace[1641090960] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:590; }","duration":"875.470297ms","start":"2026-05-19T17:52:10.289399Z","end":"2026-05-19T17:52:11.164869Z","steps":["trace[1641090960] 'range keys from in-memory index tree' (duration: 875.266741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.275456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592727473s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:11.276165Z","caller":"traceutil/trace.go:171","msg":"trace[971407976] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"2.476384417s","start":"2026-05-19T17:52:08.79974Z","end":"2026-05-19T17:52:11.276125Z","steps":["trace[971407976] 'process raft request' (duration: 2.476065287s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:11.276255Z","caller":"traceutil/trace.go:171","msg":"trace[991109383] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.460088835s","start":"2026-05-19T17:52:08.816133Z","end":"2026-05-19T17:52:11.276222Z","steps":["trace[991109383] 'process raft request' (duration: 2.45990348s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.276403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726172787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:52:11.276429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:08.816088Z","time spent":"2.46026746s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:11.27627Z","caller":"traceutil/trace.go:171","msg":"trace[444092934] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"1.725954761s","start":"2026-05-19T17:52:09.550213Z","end":"2026-05-19T17:52:11.276168Z","steps":["trace[444092934] 'read index received' (duration: 131.687132ms)","trace[444092934] 'applied index is now lower than readState.Index' (duration: 1.594266739s)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:11.276466Z","caller":"traceutil/trace.go:171","msg":"trace[272382899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.72627803s","start":"2026-05-19T17:52:09.550172Z","end":"2026-05-19T17:52:11.27645Z","steps":["trace[272382899] 'agreement among raft nodes before linearized reading' (duration: 1.726154617s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.276514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:09.550153Z","time spent":"1.726350343s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:11.276437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:08.799689Z","time spent":"2.476612914s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:11.776884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:52:11.811971Z","caller":"traceutil/trace.go:171","msg":"trace[363508236] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.486175405s","start":"2026-05-19T17:52:10.32575Z","end":"2026-05-19T17:52:11.811925Z","steps":["trace[363508236] 'process raft request' (duration: 1.486089012s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:11.811997Z","caller":"traceutil/trace.go:171","msg":"trace[1080779072] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.101870109s","start":"2026-05-19T17:52:09.710081Z","end":"2026-05-19T17:52:11.811951Z","steps":["trace[1080779072] 'process raft request' (duration: 2.087261153s)","trace[1080779072] 'compare' (duration: 14.269576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:11.812156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.325725Z","time spent":"1.48633256s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:11.812234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:09.710042Z","time spent":"2.102051065s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:11.952823Z","caller":"traceutil/trace.go:171","msg":"trace[734486086] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"676.298819ms","start":"2026-05-19T17:52:11.276492Z","end":"2026-05-19T17:52:11.952791Z","steps":["trace[734486086] 'read index received' (duration: 520.533465ms)","trace[734486086] 'applied index is now lower than readState.Index' (duration: 155.764104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:11.953012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271765706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T17:52:11.953085Z","caller":"traceutil/trace.go:171","msg":"trace[40777872] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"657.914239ms","start":"2026-05-19T17:52:11.295143Z","end":"2026-05-19T17:52:11.953058Z","steps":["trace[40777872] 'process raft request' (duration: 656.711363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.953208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922858341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T17:52:11.953303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:11.295122Z","time spent":"658.055384ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:11.953332Z","caller":"traceutil/trace.go:171","msg":"trace[1848914270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"1.923051697s","start":"2026-05-19T17:52:10.030255Z","end":"2026-05-19T17:52:11.953307Z","steps":["trace[1848914270] 'agreement among raft nodes before linearized reading' (duration: 1.922760497s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:11.953381Z","caller":"traceutil/trace.go:171","msg":"trace[182009897] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:595; }","duration":"1.272199439s","start":"2026-05-19T17:52:10.681126Z","end":"2026-05-19T17:52:11.953326Z","steps":["trace[182009897] 'agreement among raft nodes before linearized reading' (duration: 1.271672153s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.953066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906754004s","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-19T17:52:11.953492Z","caller":"traceutil/trace.go:171","msg":"trace[314211772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.907231179s","start":"2026-05-19T17:52:10.046238Z","end":"2026-05-19T17:52:11.953469Z","steps":["trace[314211772] 'agreement among raft nodes before linearized reading' (duration: 1.906689672s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.953588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.046216Z","time spent":"1.907352083s","remote":"127.0.0.1:51044","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-19T17:52:11.953631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51885919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T17:52:11.953418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.030234Z","time spent":"1.92317322s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:52:11.953619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.681107Z","time spent":"1.272383954s","remote":"127.0.0.1:50798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.99\" "} {"level":"info","ts":"2026-05-19T17:52:11.953677Z","caller":"traceutil/trace.go:171","msg":"trace[1321403212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"1.518899541s","start":"2026-05-19T17:52:10.434763Z","end":"2026-05-19T17:52:11.953663Z","steps":["trace[1321403212] 'agreement among raft nodes before linearized reading' (duration: 1.518713986s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.953845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.434708Z","time spent":"1.519125029s","remote":"127.0.0.1:51044","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-19T17:52:11.953379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522719078s","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-19T17:52:11.953989Z","caller":"traceutil/trace.go:171","msg":"trace[2089680393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.523422379s","start":"2026-05-19T17:52:10.430518Z","end":"2026-05-19T17:52:11.95394Z","steps":["trace[2089680393] 'agreement among raft nodes before linearized reading' (duration: 1.522345366s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:11.954128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:10.430497Z","time spent":"1.523575393s","remote":"127.0.0.1:51044","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-19T17:52:12.16968Z","caller":"traceutil/trace.go:171","msg":"trace[858381465] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"336.419335ms","start":"2026-05-19T17:52:11.833247Z","end":"2026-05-19T17:52:12.169666Z","steps":["trace[858381465] 'process raft request' (duration: 252.495852ms)","trace[858381465] 'compare' (duration: 83.710597ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:12.169834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:11.833219Z","time spent":"336.545228ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:12.169946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.037068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:52:12.169957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.027992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-19T17:52:12.169998Z","caller":"traceutil/trace.go:171","msg":"trace[811952795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"892.125251ms","start":"2026-05-19T17:52:11.277858Z","end":"2026-05-19T17:52:12.169983Z","steps":["trace[811952795] 'agreement among raft nodes before linearized reading' (duration: 892.048419ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.170028Z","caller":"traceutil/trace.go:171","msg":"trace[798070839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"854.150376ms","start":"2026-05-19T17:52:11.315859Z","end":"2026-05-19T17:52:12.170009Z","steps":["trace[798070839] 'agreement among raft nodes before linearized reading' (duration: 853.839666ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.169633Z","caller":"traceutil/trace.go:171","msg":"trace[118823978] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"216.712499ms","start":"2026-05-19T17:52:11.952898Z","end":"2026-05-19T17:52:12.169611Z","steps":["trace[118823978] 'read index received' (duration: 132.865568ms)","trace[118823978] 'applied index is now lower than readState.Index' (duration: 83.846001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:12.170093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:11.315837Z","time spent":"854.242809ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-19T17:52:12.170048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:11.27777Z","time spent":"892.267685ms","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:12.170024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.65345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:12.170239Z","caller":"traceutil/trace.go:171","msg":"trace[1523308606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"524.958069ms","start":"2026-05-19T17:52:11.645262Z","end":"2026-05-19T17:52:12.17022Z","steps":["trace[1523308606] 'agreement among raft nodes before linearized reading' (duration: 524.416933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:12.170368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:11.645243Z","time spent":"525.108504ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:52:12.223101Z","caller":"traceutil/trace.go:171","msg":"trace[1741406170] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"264.026516ms","start":"2026-05-19T17:52:11.959055Z","end":"2026-05-19T17:52:12.223082Z","steps":["trace[1741406170] 'process raft request' (duration: 263.542902ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.223406Z","caller":"traceutil/trace.go:171","msg":"trace[1385714125] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"264.230303ms","start":"2026-05-19T17:52:11.959169Z","end":"2026-05-19T17:52:12.223399Z","steps":["trace[1385714125] 'process raft request' (duration: 263.708637ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.22346Z","caller":"traceutil/trace.go:171","msg":"trace[636729627] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"263.067028ms","start":"2026-05-19T17:52:11.960359Z","end":"2026-05-19T17:52:12.223426Z","steps":["trace[636729627] 'process raft request' (duration: 263.016547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.223512Z","caller":"traceutil/trace.go:171","msg":"trace[2017306712] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"263.336017ms","start":"2026-05-19T17:52:11.960169Z","end":"2026-05-19T17:52:12.223505Z","steps":["trace[2017306712] 'process raft request' (duration: 263.157391ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.483673Z","caller":"traceutil/trace.go:171","msg":"trace[5429652] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"397.413101ms","start":"2026-05-19T17:52:12.086211Z","end":"2026-05-19T17:52:12.483624Z","steps":["trace[5429652] 'process raft request' (duration: 391.164746ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.483732Z","caller":"traceutil/trace.go:171","msg":"trace[1397655663] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"274.776412ms","start":"2026-05-19T17:52:12.208926Z","end":"2026-05-19T17:52:12.483703Z","steps":["trace[1397655663] 'process raft request' (duration: 274.642438ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:12.483732Z","caller":"traceutil/trace.go:171","msg":"trace[309559532] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:621; }","duration":"302.700019ms","start":"2026-05-19T17:52:12.180983Z","end":"2026-05-19T17:52:12.483683Z","steps":["trace[309559532] 'read index received' (duration: 41.19428ms)","trace[309559532] 'applied index is now lower than readState.Index' (duration: 261.504599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:12.483861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.086187Z","time spent":"397.577556ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:12.483939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.934805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-05-19T17:52:12.484009Z","caller":"traceutil/trace.go:171","msg":"trace[1373123480] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"303.038569ms","start":"2026-05-19T17:52:12.180951Z","end":"2026-05-19T17:52:12.48399Z","steps":["trace[1373123480] 'agreement among raft nodes before linearized reading' (duration: 302.831773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:12.484056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.180932Z","time spent":"303.11069ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10892,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-19T17:52:12.492938Z","caller":"traceutil/trace.go:171","msg":"trace[1450386744] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"268.552788ms","start":"2026-05-19T17:52:12.224352Z","end":"2026-05-19T17:52:12.492905Z","steps":["trace[1450386744] 'process raft request' (duration: 268.327062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.051364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:13.552088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:13.687419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.138622675s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:13.759872Z","caller":"traceutil/trace.go:171","msg":"trace[550082906] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.211205312s","start":"2026-05-19T17:52:12.548627Z","end":"2026-05-19T17:52:13.759832Z","steps":["trace[550082906] 'process raft request' (duration: 1.139043888s)","trace[550082906] 'compare' (duration: 71.67844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:13.760092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.548606Z","time spent":"1.211368038s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:13.772654Z","caller":"traceutil/trace.go:171","msg":"trace[1400844344] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.054087889s","start":"2026-05-19T17:52:12.718543Z","end":"2026-05-19T17:52:13.772631Z","steps":["trace[1400844344] 'process raft request' (duration: 1.053205853s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.772824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.718518Z","time spent":"1.054229772s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:13.773197Z","caller":"traceutil/trace.go:171","msg":"trace[938468929] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"1.222329517s","start":"2026-05-19T17:52:12.550856Z","end":"2026-05-19T17:52:13.773186Z","steps":["trace[938468929] 'read index received' (duration: 1.136885623s)","trace[938468929] 'applied index is now lower than readState.Index' (duration: 85.443074ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:13.77334Z","caller":"traceutil/trace.go:171","msg":"trace[642102259] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"136.939354ms","start":"2026-05-19T17:52:13.636394Z","end":"2026-05-19T17:52:13.773333Z","steps":["trace[642102259] 'process raft request' (duration: 136.174182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.773447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222587885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:13.773491Z","caller":"traceutil/trace.go:171","msg":"trace[1622348732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.222663367s","start":"2026-05-19T17:52:12.550815Z","end":"2026-05-19T17:52:13.773478Z","steps":["trace[1622348732] 'agreement among raft nodes before linearized reading' (duration: 1.222608636s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.773527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.550796Z","time spent":"1.222722128s","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:13.773876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222862752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-19T17:52:13.773916Z","caller":"traceutil/trace.go:171","msg":"trace[1179645820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:607; }","duration":"1.222902384s","start":"2026-05-19T17:52:12.551003Z","end":"2026-05-19T17:52:13.773905Z","steps":["trace[1179645820] 'agreement among raft nodes before linearized reading' (duration: 1.222759479s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.77394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:12.550959Z","time spent":"1.222975305s","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-19T17:52:14.017704Z","caller":"traceutil/trace.go:171","msg":"trace[769970943] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"193.008353ms","start":"2026-05-19T17:52:13.824667Z","end":"2026-05-19T17:52:14.017676Z","steps":["trace[769970943] 'read index received' (duration: 191.364406ms)","trace[769970943] 'applied index is now lower than readState.Index' (duration: 1.642607ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:14.01778Z","caller":"traceutil/trace.go:171","msg":"trace[1377695930] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"212.717722ms","start":"2026-05-19T17:52:13.805007Z","end":"2026-05-19T17:52:14.017724Z","steps":["trace[1377695930] 'process raft request' (duration: 211.061784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:14.017916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.222911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-19T17:52:14.017971Z","caller":"traceutil/trace.go:171","msg":"trace[1202282941] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:608; }","duration":"193.349584ms","start":"2026-05-19T17:52:13.824603Z","end":"2026-05-19T17:52:14.017952Z","steps":["trace[1202282941] 'agreement among raft nodes before linearized reading' (duration: 193.169048ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:14.035876Z","caller":"traceutil/trace.go:171","msg":"trace[1595297367] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"183.385997ms","start":"2026-05-19T17:52:13.852447Z","end":"2026-05-19T17:52:14.035833Z","steps":["trace[1595297367] 'process raft request' (duration: 183.083518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:16.748321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:17.104162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.17008984s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:17.106877Z","caller":"traceutil/trace.go:171","msg":"trace[863117364] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.175202886s","start":"2026-05-19T17:52:15.9316Z","end":"2026-05-19T17:52:17.106803Z","steps":["trace[863117364] 'process raft request' (duration: 1.173590411s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.107084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:15.931551Z","time spent":"1.175445083s","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:17.594594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.430649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T17:52:17.594811Z","caller":"traceutil/trace.go:171","msg":"trace[993478463] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"1.347149577s","start":"2026-05-19T17:52:16.247581Z","end":"2026-05-19T17:52:17.59473Z","steps":["trace[993478463] 'read index received' (duration: 856.873908ms)","trace[993478463] 'applied index is now lower than readState.Index' (duration: 490.273129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:17.595034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347430475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T17:52:17.595078Z","caller":"traceutil/trace.go:171","msg":"trace[1892726122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.347514897s","start":"2026-05-19T17:52:16.247549Z","end":"2026-05-19T17:52:17.595064Z","steps":["trace[1892726122] 'agreement among raft nodes before linearized reading' (duration: 1.347366743s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.595124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:16.247533Z","time spent":"1.34758058s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:52:17.595235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347492167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:52:17.595395Z","caller":"traceutil/trace.go:171","msg":"trace[1720739467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.34763108s","start":"2026-05-19T17:52:16.247685Z","end":"2026-05-19T17:52:17.595316Z","steps":["trace[1720739467] 'agreement among raft nodes before linearized reading' (duration: 1.347421205s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.595453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:16.247667Z","time spent":"1.347775195s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:52:17.595482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347689693s","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-19T17:52:17.595558Z","caller":"traceutil/trace.go:171","msg":"trace[1316082478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.347798606s","start":"2026-05-19T17:52:16.247736Z","end":"2026-05-19T17:52:17.595535Z","steps":["trace[1316082478] 'agreement among raft nodes before linearized reading' (duration: 1.347655562s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.595539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043529276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:52:17.595627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347896908s","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-19T17:52:17.595696Z","caller":"traceutil/trace.go:171","msg":"trace[1754951774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"1.347990971s","start":"2026-05-19T17:52:16.247688Z","end":"2026-05-19T17:52:17.595679Z","steps":["trace[1754951774] 'agreement among raft nodes before linearized reading' (duration: 1.347865797s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:17.595705Z","caller":"traceutil/trace.go:171","msg":"trace[1075714360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.043737792s","start":"2026-05-19T17:52:16.551921Z","end":"2026-05-19T17:52:17.595659Z","steps":["trace[1075714360] 'agreement among raft nodes before linearized reading' (duration: 1.043465864s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.595739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:16.247668Z","time spent":"1.348063253s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:52:17.595769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:16.551899Z","time spent":"1.043856246s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:17.595604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:16.247711Z","time spent":"1.347881947s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:52:17.596041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.805422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:17.59618Z","caller":"traceutil/trace.go:171","msg":"trace[1644478050] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"423.996858ms","start":"2026-05-19T17:52:17.172151Z","end":"2026-05-19T17:52:17.596148Z","steps":["trace[1644478050] 'agreement among raft nodes before linearized reading' (duration: 423.470553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:17.596329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:17.17213Z","time spent":"424.184493ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-19T17:52:17.802046Z","caller":"traceutil/trace.go:171","msg":"trace[652608266] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"201.356914ms","start":"2026-05-19T17:52:17.600651Z","end":"2026-05-19T17:52:17.802008Z","steps":["trace[652608266] 'process raft request' (duration: 179.662962ms)","trace[652608266] 'compare' (duration: 21.387314ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:17.802049Z","caller":"traceutil/trace.go:171","msg":"trace[1047891585] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"200.937111ms","start":"2026-05-19T17:52:17.601078Z","end":"2026-05-19T17:52:17.802015Z","steps":["trace[1047891585] 'process raft request' (duration: 200.846229ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:17.802313Z","caller":"traceutil/trace.go:171","msg":"trace[1306370118] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"189.293542ms","start":"2026-05-19T17:52:17.613007Z","end":"2026-05-19T17:52:17.8023Z","steps":["trace[1306370118] 'read index received' (duration: 35.150241ms)","trace[1306370118] 'applied index is now lower than readState.Index' (duration: 154.142131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:17.802444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.406766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:17.802483Z","caller":"traceutil/trace.go:171","msg":"trace[800883312] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"200.328365ms","start":"2026-05-19T17:52:17.602145Z","end":"2026-05-19T17:52:17.802473Z","steps":["trace[800883312] 'process raft request' (duration: 199.8268ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:17.802504Z","caller":"traceutil/trace.go:171","msg":"trace[46579316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"189.52452ms","start":"2026-05-19T17:52:17.612968Z","end":"2026-05-19T17:52:17.802493Z","steps":["trace[46579316] 'agreement among raft nodes before linearized reading' (duration: 189.382705ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:18.127308Z","caller":"traceutil/trace.go:171","msg":"trace[671304137] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"102.193808ms","start":"2026-05-19T17:52:18.025086Z","end":"2026-05-19T17:52:18.12728Z","steps":["trace[671304137] 'process raft request' (duration: 100.077519ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:19.253694Z","caller":"traceutil/trace.go:171","msg":"trace[539654947] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"125.148289ms","start":"2026-05-19T17:52:19.128509Z","end":"2026-05-19T17:52:19.253657Z","steps":["trace[539654947] 'process raft request' (duration: 119.211983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:19.500847Z","caller":"traceutil/trace.go:171","msg":"trace[1315461483] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"194.668725ms","start":"2026-05-19T17:52:19.306139Z","end":"2026-05-19T17:52:19.500808Z","steps":["trace[1315461483] 'process raft request' (duration: 101.369029ms)","trace[1315461483] 'compare' (duration: 92.957347ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:20.039346Z","caller":"traceutil/trace.go:171","msg":"trace[1322072356] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"101.395061ms","start":"2026-05-19T17:52:19.937911Z","end":"2026-05-19T17:52:20.039306Z","steps":["trace[1322072356] 'process raft request' (duration: 99.632522ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:20.636998Z","caller":"traceutil/trace.go:171","msg":"trace[2065955937] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"110.721339ms","start":"2026-05-19T17:52:20.52625Z","end":"2026-05-19T17:52:20.636972Z","steps":["trace[2065955937] 'process raft request' (duration: 110.153954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:20.841895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.208553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:52:20.841992Z","caller":"traceutil/trace.go:171","msg":"trace[582028131] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"128.013985ms","start":"2026-05-19T17:52:20.713962Z","end":"2026-05-19T17:52:20.841976Z","steps":["trace[582028131] 'compare' (duration: 123.068209ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:21.458349Z","caller":"traceutil/trace.go:171","msg":"trace[1638278586] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"219.488223ms","start":"2026-05-19T17:52:21.238842Z","end":"2026-05-19T17:52:21.45833Z","steps":["trace[1638278586] 'process raft request' (duration: 219.04796ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:24.232118Z","caller":"traceutil/trace.go:171","msg":"trace[41349434] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"454.437651ms","start":"2026-05-19T17:52:23.777658Z","end":"2026-05-19T17:52:24.232095Z","steps":["trace[41349434] 'read index received' (duration: 454.331558ms)","trace[41349434] 'applied index is now lower than readState.Index' (duration: 104.873µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:24.232235Z","caller":"traceutil/trace.go:171","msg":"trace[167800376] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"459.491395ms","start":"2026-05-19T17:52:23.772678Z","end":"2026-05-19T17:52:24.232169Z","steps":["trace[167800376] 'process raft request' (duration: 459.29699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.232291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.623876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dk49j\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T17:52:24.232324Z","caller":"traceutil/trace.go:171","msg":"trace[347612952] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dk49j; range_end:; response_count:1; response_revision:655; }","duration":"454.710099ms","start":"2026-05-19T17:52:23.777605Z","end":"2026-05-19T17:52:24.232315Z","steps":["trace[347612952] 'agreement among raft nodes before linearized reading' (duration: 454.628347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.23235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.777583Z","time spent":"454.761969ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-dk49j\" "} {"level":"warn","ts":"2026-05-19T17:52:24.232558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.772661Z","time spent":"459.66619ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:24.361017Z","caller":"traceutil/trace.go:171","msg":"trace[1830533851] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"128.802642ms","start":"2026-05-19T17:52:24.232185Z","end":"2026-05-19T17:52:24.360988Z","steps":["trace[1830533851] 'read index received' (duration: 73.395184ms)","trace[1830533851] 'applied index is now lower than readState.Index' (duration: 55.406508ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:24.361033Z","caller":"traceutil/trace.go:171","msg":"trace[1498869848] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"490.614286ms","start":"2026-05-19T17:52:23.870372Z","end":"2026-05-19T17:52:24.360986Z","steps":["trace[1498869848] 'process raft request' (duration: 435.268469ms)","trace[1498869848] 'compare' (duration: 55.132821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:24.361186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.870351Z","time spent":"490.75573ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:24.361213Z","caller":"traceutil/trace.go:171","msg":"trace[1549500661] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"115.240402ms","start":"2026-05-19T17:52:24.245949Z","end":"2026-05-19T17:52:24.361189Z","steps":["trace[1549500661] 'process raft request' (duration: 114.976395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.36164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.30759ms","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-19T17:52:24.36167Z","caller":"traceutil/trace.go:171","msg":"trace[956779508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"416.337831ms","start":"2026-05-19T17:52:23.945321Z","end":"2026-05-19T17:52:24.361659Z","steps":["trace[956779508] 'agreement among raft nodes before linearized reading' (duration: 415.709895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.361692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.945282Z","time spent":"416.404493ms","remote":"127.0.0.1:51044","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-19T17:52:24.361752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.228771ms","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-19T17:52:24.361792Z","caller":"traceutil/trace.go:171","msg":"trace[935956282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"415.474787ms","start":"2026-05-19T17:52:23.946306Z","end":"2026-05-19T17:52:24.361781Z","steps":["trace[935956282] 'agreement among raft nodes before linearized reading' (duration: 414.702337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.361797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.276689ms","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-19T17:52:24.361832Z","caller":"traceutil/trace.go:171","msg":"trace[1196693083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"456.370851ms","start":"2026-05-19T17:52:23.905454Z","end":"2026-05-19T17:52:24.361824Z","steps":["trace[1196693083] 'agreement among raft nodes before linearized reading' (duration: 455.624852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:24.361832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.946282Z","time spent":"415.54293ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:52:24.361855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:23.905423Z","time spent":"456.426412ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:52:25.052015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:25.553141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:26.053862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:26.194702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.80700723s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:26.19535Z","caller":"traceutil/trace.go:171","msg":"trace[1009972723] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.827660926s","start":"2026-05-19T17:52:24.367661Z","end":"2026-05-19T17:52:26.195322Z","steps":["trace[1009972723] 'process raft request' (duration: 1.827155553s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:26.195366Z","caller":"traceutil/trace.go:171","msg":"trace[1536706267] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.827072841s","start":"2026-05-19T17:52:24.368263Z","end":"2026-05-19T17:52:26.195336Z","steps":["trace[1536706267] 'process raft request' (duration: 1.82669716s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:26.195501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:24.367631Z","time spent":"1.82777955s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:26.195522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:24.368243Z","time spent":"1.827187594s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:26.195631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644091024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:26.19572Z","caller":"traceutil/trace.go:171","msg":"trace[1066908065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"1.644233037s","start":"2026-05-19T17:52:24.551463Z","end":"2026-05-19T17:52:26.195696Z","steps":["trace[1066908065] 'agreement among raft nodes before linearized reading' (duration: 1.644061753s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:26.195766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:24.551425Z","time spent":"1.64433275s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:52:26.195976Z","caller":"traceutil/trace.go:171","msg":"trace[279925120] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"1.643753775s","start":"2026-05-19T17:52:24.551527Z","end":"2026-05-19T17:52:26.195281Z","steps":["trace[279925120] 'read index received' (duration: 1.643269622s)","trace[279925120] 'applied index is now lower than readState.Index' (duration: 482.913µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:26.893272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:27.393986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867902399091830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:52:27.40101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159598786s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:27.407115Z","caller":"traceutil/trace.go:171","msg":"trace[931441265] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"1.0145237s","start":"2026-05-19T17:52:26.392553Z","end":"2026-05-19T17:52:27.407077Z","steps":["trace[931441265] 'read index received' (duration: 1.008875024s)","trace[931441265] 'applied index is now lower than readState.Index' (duration: 5.647096ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:27.407303Z","caller":"traceutil/trace.go:171","msg":"trace[1116898561] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.165980461s","start":"2026-05-19T17:52:26.241302Z","end":"2026-05-19T17:52:27.407282Z","steps":["trace[1116898561] 'process raft request' (duration: 1.160020217s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:27.40785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:26.241277Z","time spent":"1.166473984s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:27.407388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014831608s","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-19T17:52:27.40812Z","caller":"traceutil/trace.go:171","msg":"trace[82784269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.015583368s","start":"2026-05-19T17:52:26.392501Z","end":"2026-05-19T17:52:27.408084Z","steps":["trace[82784269] 'agreement among raft nodes before linearized reading' (duration: 1.014841979s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:27.408326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:26.392478Z","time spent":"1.015804073s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:52:27.969866Z","caller":"traceutil/trace.go:171","msg":"trace[819424264] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"173.595828ms","start":"2026-05-19T17:52:27.796246Z","end":"2026-05-19T17:52:27.969842Z","steps":["trace[819424264] 'process raft request' (duration: 173.425314ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:27.981068Z","caller":"traceutil/trace.go:171","msg":"trace[1968475537] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"169.899894ms","start":"2026-05-19T17:52:27.811152Z","end":"2026-05-19T17:52:27.981052Z","steps":["trace[1968475537] 'process raft request' (duration: 169.77526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:29.885668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.236713ms","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-19T17:52:29.885765Z","caller":"traceutil/trace.go:171","msg":"trace[926840835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"459.359967ms","start":"2026-05-19T17:52:29.426381Z","end":"2026-05-19T17:52:29.885741Z","steps":["trace[926840835] 'range keys from in-memory index tree' (duration: 459.036159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:29.885811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:29.426364Z","time spent":"459.436139ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:52:29.885884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.606782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T17:52:29.885652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.36382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:29.885941Z","caller":"traceutil/trace.go:171","msg":"trace[350424881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:675; }","duration":"939.729139ms","start":"2026-05-19T17:52:28.946197Z","end":"2026-05-19T17:52:29.885926Z","steps":["trace[350424881] 'range keys from in-memory index tree' (duration: 939.272227ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:29.885945Z","caller":"traceutil/trace.go:171","msg":"trace[1539281027] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:675; }","duration":"117.732005ms","start":"2026-05-19T17:52:29.768188Z","end":"2026-05-19T17:52:29.88592Z","steps":["trace[1539281027] 'count revisions from in-memory index tree' (duration: 117.499719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:29.88598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:28.946173Z","time spent":"939.795492ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:52:29.886003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.776244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:29.886113Z","caller":"traceutil/trace.go:171","msg":"trace[744893691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"335.965079ms","start":"2026-05-19T17:52:29.550126Z","end":"2026-05-19T17:52:29.886091Z","steps":["trace[744893691] 'range keys from in-memory index tree' (duration: 335.656291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:29.886151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:29.550093Z","time spent":"336.049381ms","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:29.88618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.337296ms","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-19T17:52:29.886478Z","caller":"traceutil/trace.go:171","msg":"trace[1284975196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"472.593123ms","start":"2026-05-19T17:52:29.413785Z","end":"2026-05-19T17:52:29.886378Z","steps":["trace[1284975196] 'range keys from in-memory index tree' (duration: 471.851074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:29.887628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:29.413727Z","time spent":"472.84598ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:52:32.705825Z","caller":"traceutil/trace.go:171","msg":"trace[335895375] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"119.556076ms","start":"2026-05-19T17:52:32.586239Z","end":"2026-05-19T17:52:32.705795Z","steps":["trace[335895375] 'process raft request' (duration: 103.841827ms)","trace[335895375] 'compare' (duration: 15.549074ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:33.194046Z","caller":"traceutil/trace.go:171","msg":"trace[735622080] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"357.103257ms","start":"2026-05-19T17:52:32.83691Z","end":"2026-05-19T17:52:33.194013Z","steps":["trace[735622080] 'read index received' (duration: 356.934133ms)","trace[735622080] 'applied index is now lower than readState.Index' (duration: 167.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:33.194381Z","caller":"traceutil/trace.go:171","msg":"trace[2029290459] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"377.83041ms","start":"2026-05-19T17:52:32.81653Z","end":"2026-05-19T17:52:33.19436Z","steps":["trace[2029290459] 'process raft request' (duration: 377.239726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.194605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.675242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dk49j\" ","response":"range_response_count:1 size:18063"} {"level":"info","ts":"2026-05-19T17:52:33.19468Z","caller":"traceutil/trace.go:171","msg":"trace[558281491] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dk49j; range_end:; response_count:1; response_revision:729; }","duration":"357.806555ms","start":"2026-05-19T17:52:32.836855Z","end":"2026-05-19T17:52:33.194661Z","steps":["trace[558281491] 'agreement among raft nodes before linearized reading' (duration: 357.550149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.194728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:32.836805Z","time spent":"357.909947ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18085,"request content":"key:\"/registry/pods/kube-system/cilium-dk49j\" "} {"level":"warn","ts":"2026-05-19T17:52:33.194733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:32.816502Z","time spent":"378.019375ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:52:33.385238Z","caller":"traceutil/trace.go:171","msg":"trace[922257113] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"190.92889ms","start":"2026-05-19T17:52:33.194246Z","end":"2026-05-19T17:52:33.38521Z","steps":["trace[922257113] 'read index received' (duration: 190.868689ms)","trace[922257113] 'applied index is now lower than readState.Index' (duration: 58.651µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:33.385312Z","caller":"traceutil/trace.go:171","msg":"trace[2053573013] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"545.499525ms","start":"2026-05-19T17:52:32.839785Z","end":"2026-05-19T17:52:33.385284Z","steps":["trace[2053573013] 'process raft request' (duration: 545.222058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.385422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:32.839763Z","time spent":"545.608338ms","remote":"127.0.0.1:50830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:33.385532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.245923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-19T17:52:33.385605Z","caller":"traceutil/trace.go:171","msg":"trace[400541645] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:731; }","duration":"404.340425ms","start":"2026-05-19T17:52:32.981247Z","end":"2026-05-19T17:52:33.385587Z","steps":["trace[400541645] 'agreement among raft nodes before linearized reading' (duration: 404.104589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.385665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:32.981158Z","time spent":"404.488839ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-19T17:52:33.385294Z","caller":"traceutil/trace.go:171","msg":"trace[1505929547] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"224.675187ms","start":"2026-05-19T17:52:33.160574Z","end":"2026-05-19T17:52:33.385249Z","steps":["trace[1505929547] 'process raft request' (duration: 224.572414ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:33.84289Z","caller":"traceutil/trace.go:171","msg":"trace[1152106969] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"626.182608ms","start":"2026-05-19T17:52:33.216666Z","end":"2026-05-19T17:52:33.842849Z","steps":["trace[1152106969] 'process raft request' (duration: 573.856476ms)","trace[1152106969] 'compare' (duration: 51.931362ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:33.843091Z","caller":"traceutil/trace.go:171","msg":"trace[884310870] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"457.725209ms","start":"2026-05-19T17:52:33.385327Z","end":"2026-05-19T17:52:33.843052Z","steps":["trace[884310870] 'read index received' (duration: 405.238463ms)","trace[884310870] 'applied index is now lower than readState.Index' (duration: 52.484626ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:33.843137Z","caller":"traceutil/trace.go:171","msg":"trace[1390324893] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"620.456799ms","start":"2026-05-19T17:52:33.222648Z","end":"2026-05-19T17:52:33.843105Z","steps":["trace[1390324893] 'process raft request' (duration: 620.093009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.843151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.216628Z","time spent":"626.425684ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:33.843322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.776639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-05-19T17:52:33.843316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.222613Z","time spent":"620.612983ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:52:33.843367Z","caller":"traceutil/trace.go:171","msg":"trace[588509872] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:733; }","duration":"461.878121ms","start":"2026-05-19T17:52:33.381476Z","end":"2026-05-19T17:52:33.843354Z","steps":["trace[588509872] 'agreement among raft nodes before linearized reading' (duration: 461.693757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:33.843392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.381447Z","time spent":"461.939443ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-05-19T17:52:34.187514Z","caller":"traceutil/trace.go:171","msg":"trace[407000370] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"344.287251ms","start":"2026-05-19T17:52:33.8432Z","end":"2026-05-19T17:52:34.187487Z","steps":["trace[407000370] 'read index received' (duration: 344.068526ms)","trace[407000370] 'applied index is now lower than readState.Index' (duration: 217.585µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:34.187681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.076348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:34.18772Z","caller":"traceutil/trace.go:171","msg":"trace[1882266915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"637.178511ms","start":"2026-05-19T17:52:33.550526Z","end":"2026-05-19T17:52:34.187705Z","steps":["trace[1882266915] 'agreement among raft nodes before linearized reading' (duration: 637.094188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.18769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.903888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:52:34.187759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.550503Z","time spent":"637.248673ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:52:34.187811Z","caller":"traceutil/trace.go:171","msg":"trace[465342539] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:734; }","duration":"567.077183ms","start":"2026-05-19T17:52:33.620694Z","end":"2026-05-19T17:52:34.187772Z","steps":["trace[465342539] 'agreement among raft nodes before linearized reading' (duration: 566.877657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.188402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.62068Z","time spent":"567.701227ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:34.187795Z","caller":"traceutil/trace.go:171","msg":"trace[1281800978] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"576.148976ms","start":"2026-05-19T17:52:33.611622Z","end":"2026-05-19T17:52:34.187771Z","steps":["trace[1281800978] 'process raft request' (duration: 575.650313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.188684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.611562Z","time spent":"577.045307ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:52:34.187705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.496132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:34.188961Z","caller":"traceutil/trace.go:171","msg":"trace[193445422] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:734; }","duration":"373.817485ms","start":"2026-05-19T17:52:33.815117Z","end":"2026-05-19T17:52:34.188934Z","steps":["trace[193445422] 'agreement among raft nodes before linearized reading' (duration: 372.39518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.187748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.482737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-05-19T17:52:34.18915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.815093Z","time spent":"373.948778ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:52:34.189216Z","caller":"traceutil/trace.go:171","msg":"trace[919148410] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:734; }","duration":"408.005484ms","start":"2026-05-19T17:52:33.781173Z","end":"2026-05-19T17:52:34.189178Z","steps":["trace[919148410] 'agreement among raft nodes before linearized reading' (duration: 406.368383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.189428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.781138Z","time spent":"408.2586ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-19T17:52:34.335095Z","caller":"traceutil/trace.go:171","msg":"trace[519720252] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"147.465425ms","start":"2026-05-19T17:52:34.187592Z","end":"2026-05-19T17:52:34.335057Z","steps":["trace[519720252] 'read index received' (duration: 143.994011ms)","trace[519720252] 'applied index is now lower than readState.Index' (duration: 3.470234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:34.335455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.602447ms","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-19T17:52:34.335505Z","caller":"traceutil/trace.go:171","msg":"trace[1514067906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"339.73446ms","start":"2026-05-19T17:52:33.995756Z","end":"2026-05-19T17:52:34.33549Z","steps":["trace[1514067906] 'agreement among raft nodes before linearized reading' (duration: 339.499255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.335508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.88969ms","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-19T17:52:34.335595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.085898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T17:52:34.335551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.995738Z","time spent":"339.802962ms","remote":"127.0.0.1:51044","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-19T17:52:34.335623Z","caller":"traceutil/trace.go:171","msg":"trace[371488628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"368.064705ms","start":"2026-05-19T17:52:33.96753Z","end":"2026-05-19T17:52:34.335595Z","steps":["trace[371488628] 'agreement among raft nodes before linearized reading' (duration: 367.754877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.335686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.005849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-19T17:52:34.335755Z","caller":"traceutil/trace.go:171","msg":"trace[1794157680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:735; }","duration":"354.107492ms","start":"2026-05-19T17:52:33.981629Z","end":"2026-05-19T17:52:34.335737Z","steps":["trace[1794157680] 'agreement among raft nodes before linearized reading' (duration: 353.904297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.335813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.981619Z","time spent":"354.181194ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-19T17:52:34.335586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.648765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:34.335829Z","caller":"traceutil/trace.go:171","msg":"trace[2128802806] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"386.144357ms","start":"2026-05-19T17:52:33.949664Z","end":"2026-05-19T17:52:34.335809Z","steps":["trace[2128802806] 'process raft request' (duration: 382.061688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.335807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.967503Z","time spent":"368.246619ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:52:34.335861Z","caller":"traceutil/trace.go:171","msg":"trace[1493635006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"146.974453ms","start":"2026-05-19T17:52:34.188877Z","end":"2026-05-19T17:52:34.335851Z","steps":["trace[1493635006] 'agreement among raft nodes before linearized reading' (duration: 146.622065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.335814Z","caller":"traceutil/trace.go:171","msg":"trace[1446776713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"351.214012ms","start":"2026-05-19T17:52:33.984453Z","end":"2026-05-19T17:52:34.335667Z","steps":["trace[1446776713] 'agreement among raft nodes before linearized reading' (duration: 350.985996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.335976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.984444Z","time spent":"351.473938ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:52:34.336007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:33.949633Z","time spent":"386.236129ms","remote":"127.0.0.1:50802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:34.335358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.707586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-19T17:52:34.336345Z","caller":"traceutil/trace.go:171","msg":"trace[1499406421] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:735; }","duration":"154.773443ms","start":"2026-05-19T17:52:34.181555Z","end":"2026-05-19T17:52:34.336328Z","steps":["trace[1499406421] 'agreement among raft nodes before linearized reading' (duration: 153.560343ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.456815Z","caller":"traceutil/trace.go:171","msg":"trace[797698600] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"114.337239ms","start":"2026-05-19T17:52:34.342436Z","end":"2026-05-19T17:52:34.456773Z","steps":["trace[797698600] 'process raft request' (duration: 114.231407ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.457055Z","caller":"traceutil/trace.go:171","msg":"trace[545513944] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"114.943414ms","start":"2026-05-19T17:52:34.342096Z","end":"2026-05-19T17:52:34.45704Z","steps":["trace[545513944] 'process raft request' (duration: 104.234323ms)","trace[545513944] 'compare' (duration: 10.150296ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:34.457231Z","caller":"traceutil/trace.go:171","msg":"trace[1622421875] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"113.732205ms","start":"2026-05-19T17:52:34.343489Z","end":"2026-05-19T17:52:34.457221Z","steps":["trace[1622421875] 'process raft request' (duration: 113.234073ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.457678Z","caller":"traceutil/trace.go:171","msg":"trace[1958465172] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"114.119625ms","start":"2026-05-19T17:52:34.343542Z","end":"2026-05-19T17:52:34.457662Z","steps":["trace[1958465172] 'process raft request' (duration: 113.633063ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.546065Z","caller":"traceutil/trace.go:171","msg":"trace[1882514248] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"155.386737ms","start":"2026-05-19T17:52:34.39064Z","end":"2026-05-19T17:52:34.546027Z","steps":["trace[1882514248] 'process raft request' (duration: 154.895905ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.546304Z","caller":"traceutil/trace.go:171","msg":"trace[878384902] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"136.406026ms","start":"2026-05-19T17:52:34.409863Z","end":"2026-05-19T17:52:34.546269Z","steps":["trace[878384902] 'process raft request' (duration: 135.953184ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.54661Z","caller":"traceutil/trace.go:171","msg":"trace[1479691339] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"136.58884ms","start":"2026-05-19T17:52:34.410001Z","end":"2026-05-19T17:52:34.54659Z","steps":["trace[1479691339] 'process raft request' (duration: 136.042566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:34.546962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.103698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-19T17:52:34.547025Z","caller":"traceutil/trace.go:171","msg":"trace[923502601] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:744; }","duration":"166.217921ms","start":"2026-05-19T17:52:34.38079Z","end":"2026-05-19T17:52:34.547008Z","steps":["trace[923502601] 'agreement among raft nodes before linearized reading' (duration: 165.966665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.54717Z","caller":"traceutil/trace.go:171","msg":"trace[1848142227] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"113.482189ms","start":"2026-05-19T17:52:34.433675Z","end":"2026-05-19T17:52:34.547157Z","steps":["trace[1848142227] 'process raft request' (duration: 112.524805ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.867938Z","caller":"traceutil/trace.go:171","msg":"trace[1300434406] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"155.345015ms","start":"2026-05-19T17:52:34.712562Z","end":"2026-05-19T17:52:34.867907Z","steps":["trace[1300434406] 'read index received' (duration: 154.993107ms)","trace[1300434406] 'applied index is now lower than readState.Index' (duration: 350.898µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:34.868349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.731735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-19T17:52:34.868507Z","caller":"traceutil/trace.go:171","msg":"trace[1225089830] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"155.931241ms","start":"2026-05-19T17:52:34.712543Z","end":"2026-05-19T17:52:34.868474Z","steps":["trace[1225089830] 'agreement among raft nodes before linearized reading' (duration: 155.52041ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:34.86884Z","caller":"traceutil/trace.go:171","msg":"trace[2115191839] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"229.055308ms","start":"2026-05-19T17:52:34.639759Z","end":"2026-05-19T17:52:34.868814Z","steps":["trace[2115191839] 'process raft request' (duration: 227.862889ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:44.902046Z","caller":"traceutil/trace.go:171","msg":"trace[151724588] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"396.832169ms","start":"2026-05-19T17:52:44.505192Z","end":"2026-05-19T17:52:44.902024Z","steps":["trace[151724588] 'read index received' (duration: 396.714326ms)","trace[151724588] 'applied index is now lower than readState.Index' (duration: 116.853µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:44.902254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.068023ms","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-19T17:52:44.902287Z","caller":"traceutil/trace.go:171","msg":"trace[1634775306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"397.141515ms","start":"2026-05-19T17:52:44.505136Z","end":"2026-05-19T17:52:44.902278Z","steps":["trace[1634775306] 'agreement among raft nodes before linearized reading' (duration: 397.004322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.902327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.50512Z","time spent":"397.198987ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:52:44.90248Z","caller":"traceutil/trace.go:171","msg":"trace[51697752] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"470.722797ms","start":"2026-05-19T17:52:44.431729Z","end":"2026-05-19T17:52:44.902452Z","steps":["trace[51697752] 'process raft request' (duration: 470.141654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.902624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.431702Z","time spent":"470.84339ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:44.934976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.636515ms","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-19T17:52:44.935034Z","caller":"traceutil/trace.go:171","msg":"trace[1093332184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"429.771297ms","start":"2026-05-19T17:52:44.505248Z","end":"2026-05-19T17:52:44.935019Z","steps":["trace[1093332184] 'agreement among raft nodes before linearized reading' (duration: 429.588063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.935066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.505226Z","time spent":"429.831349ms","remote":"127.0.0.1:51044","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-19T17:52:44.935352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.750687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:52:44.935605Z","caller":"traceutil/trace.go:171","msg":"trace[2061148881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"385.043724ms","start":"2026-05-19T17:52:44.550532Z","end":"2026-05-19T17:52:44.935576Z","steps":["trace[2061148881] 'agreement among raft nodes before linearized reading' (duration: 384.740907ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:44.935675Z","caller":"traceutil/trace.go:171","msg":"trace[975056840] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"368.88845ms","start":"2026-05-19T17:52:44.566775Z","end":"2026-05-19T17:52:44.935663Z","steps":["trace[975056840] 'process raft request' (duration: 366.367234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.936693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.550519Z","time spent":"386.152049ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:52:44.936805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.566746Z","time spent":"369.996317ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:52:44.935453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.928521ms","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-19T17:52:44.935405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.830439ms","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-19T17:52:44.937241Z","caller":"traceutil/trace.go:171","msg":"trace[1562907432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"431.680221ms","start":"2026-05-19T17:52:44.505545Z","end":"2026-05-19T17:52:44.937225Z","steps":["trace[1562907432] 'agreement among raft nodes before linearized reading' (duration: 429.804819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.937288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.505535Z","time spent":"431.738342ms","remote":"127.0.0.1:51044","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-19T17:52:44.937099Z","caller":"traceutil/trace.go:171","msg":"trace[1285641539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"431.584779ms","start":"2026-05-19T17:52:44.505495Z","end":"2026-05-19T17:52:44.93708Z","steps":["trace[1285641539] 'agreement among raft nodes before linearized reading' (duration: 429.925401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:44.938028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:44.50548Z","time spent":"432.485658ms","remote":"127.0.0.1:51044","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-19T17:52:48.951235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007375853s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:52:48.951719Z","caller":"traceutil/trace.go:171","msg":"trace[968224174] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"1.007973706s","start":"2026-05-19T17:52:47.943705Z","end":"2026-05-19T17:52:48.951679Z","steps":["trace[968224174] 'process raft request' (duration: 1.00772029s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:48.95238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:52:47.943669Z","time spent":"1.008574969s","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:53:01.177877Z","caller":"traceutil/trace.go:171","msg":"trace[1580053058] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"103.63161ms","start":"2026-05-19T17:53:01.074207Z","end":"2026-05-19T17:53:01.177838Z","steps":["trace[1580053058] 'process raft request' (duration: 103.589689ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:01.177919Z","caller":"traceutil/trace.go:171","msg":"trace[1916166312] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"104.022778ms","start":"2026-05-19T17:53:01.073856Z","end":"2026-05-19T17:53:01.177879Z","steps":["trace[1916166312] 'process raft request' (duration: 103.761653ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:01.178079Z","caller":"traceutil/trace.go:171","msg":"trace[929379687] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"104.147401ms","start":"2026-05-19T17:53:01.073921Z","end":"2026-05-19T17:53:01.178068Z","steps":["trace[929379687] 'process raft request' (duration: 103.832504ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:05.376269Z","caller":"traceutil/trace.go:171","msg":"trace[978917110] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"140.294109ms","start":"2026-05-19T17:53:05.235894Z","end":"2026-05-19T17:53:05.376188Z","steps":["trace[978917110] 'process raft request' (duration: 79.827577ms)","trace[978917110] 'compare' (duration: 60.302638ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:54:28.210466Z","caller":"traceutil/trace.go:171","msg":"trace[1870602566] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"204.791888ms","start":"2026-05-19T17:54:28.005637Z","end":"2026-05-19T17:54:28.210429Z","steps":["trace[1870602566] 'process raft request' (duration: 204.571395ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:28.630491Z","caller":"traceutil/trace.go:171","msg":"trace[1123618185] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"110.883345ms","start":"2026-05-19T17:54:28.519578Z","end":"2026-05-19T17:54:28.630461Z","steps":["trace[1123618185] 'process raft request' (duration: 110.689312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:35.991204Z","caller":"traceutil/trace.go:171","msg":"trace[278344045] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"119.908722ms","start":"2026-05-19T17:54:35.871273Z","end":"2026-05-19T17:54:35.991182Z","steps":["trace[278344045] 'read index received' (duration: 70.219374ms)","trace[278344045] 'applied index is now lower than readState.Index' (duration: 49.688318ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:54:35.991395Z","caller":"traceutil/trace.go:171","msg":"trace[313616781] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"136.005539ms","start":"2026-05-19T17:54:35.855316Z","end":"2026-05-19T17:54:35.991322Z","steps":["trace[313616781] 'process raft request' (duration: 86.24793ms)","trace[313616781] 'compare' (duration: 49.411584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:54:35.991504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.682196ms","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-19T17:54:35.991589Z","caller":"traceutil/trace.go:171","msg":"trace[2049113573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"118.851018ms","start":"2026-05-19T17:54:35.872721Z","end":"2026-05-19T17:54:35.991572Z","steps":["trace[2049113573] 'agreement among raft nodes before linearized reading' (duration: 118.657456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:35.991717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.420239ms","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-19T17:54:35.991794Z","caller":"traceutil/trace.go:171","msg":"trace[460336451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"120.52916ms","start":"2026-05-19T17:54:35.871249Z","end":"2026-05-19T17:54:35.991778Z","steps":["trace[460336451] 'agreement among raft nodes before linearized reading' (duration: 120.313727ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:36.107339Z","caller":"traceutil/trace.go:171","msg":"trace[1122730372] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"111.137022ms","start":"2026-05-19T17:54:35.996179Z","end":"2026-05-19T17:54:36.107316Z","steps":["trace[1122730372] 'process raft request' (duration: 111.080841ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:36.107413Z","caller":"traceutil/trace.go:171","msg":"trace[1863723894] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"111.211292ms","start":"2026-05-19T17:54:35.996169Z","end":"2026-05-19T17:54:36.107381Z","steps":["trace[1863723894] 'process raft request' (duration: 95.065216ms)","trace[1863723894] 'compare' (duration: 15.880893ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:54:36.108063Z","caller":"traceutil/trace.go:171","msg":"trace[1338852697] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"100.076361ms","start":"2026-05-19T17:54:36.007967Z","end":"2026-05-19T17:54:36.108043Z","steps":["trace[1338852697] 'process raft request' (duration: 99.96833ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:37.652332Z","caller":"traceutil/trace.go:171","msg":"trace[660782333] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"142.581919ms","start":"2026-05-19T17:54:37.509725Z","end":"2026-05-19T17:54:37.652307Z","steps":["trace[660782333] 'process raft request' (duration: 142.348356ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:40.238958Z","caller":"traceutil/trace.go:171","msg":"trace[1904164092] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"108.181689ms","start":"2026-05-19T17:54:40.130748Z","end":"2026-05-19T17:54:40.23893Z","steps":["trace[1904164092] 'process raft request' (duration: 104.856555ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:46.396843Z","caller":"traceutil/trace.go:171","msg":"trace[986450968] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"439.202362ms","start":"2026-05-19T17:54:45.95762Z","end":"2026-05-19T17:54:46.396822Z","steps":["trace[986450968] 'read index received' (duration: 439.157762ms)","trace[986450968] 'applied index is now lower than readState.Index' (duration: 43.7µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:54:46.397075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.436095ms","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-19T17:54:46.397141Z","caller":"traceutil/trace.go:171","msg":"trace[806699008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"439.555557ms","start":"2026-05-19T17:54:45.957574Z","end":"2026-05-19T17:54:46.397129Z","steps":["trace[806699008] 'agreement among raft nodes before linearized reading' (duration: 439.377535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:46.397179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:54:45.95755Z","time spent":"439.621468ms","remote":"127.0.0.1:51044","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-19T17:54:46.396924Z","caller":"traceutil/trace.go:171","msg":"trace[2116835973] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"484.499538ms","start":"2026-05-19T17:54:45.912397Z","end":"2026-05-19T17:54:46.396896Z","steps":["trace[2116835973] 'process raft request' (duration: 484.299526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:46.39764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:54:45.912374Z","time spent":"485.206328ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:54:46.433126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.07907ms","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-19T17:54:46.433205Z","caller":"traceutil/trace.go:171","msg":"trace[1595930879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"280.213072ms","start":"2026-05-19T17:54:46.152975Z","end":"2026-05-19T17:54:46.433188Z","steps":["trace[1595930879] 'agreement among raft nodes before linearized reading' (duration: 279.950959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:46.433447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.553421ms","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-19T17:54:46.433488Z","caller":"traceutil/trace.go:171","msg":"trace[1072055743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1386; }","duration":"129.635663ms","start":"2026-05-19T17:54:46.303842Z","end":"2026-05-19T17:54:46.433478Z","steps":["trace[1072055743] 'agreement among raft nodes before linearized reading' (duration: 129.541102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:46.433616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.221995ms","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-19T17:54:46.433648Z","caller":"traceutil/trace.go:171","msg":"trace[1953422594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"278.296726ms","start":"2026-05-19T17:54:46.155343Z","end":"2026-05-19T17:54:46.43364Z","steps":["trace[1953422594] 'agreement among raft nodes before linearized reading' (duration: 278.212405ms)"],"step_count":1}