{"level":"warn","ts":"2026-08-18T23:19:17.248938Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:19:17.249022Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-08-18T23:19:17.249082Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:19:17.249095Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-18T23:19:17.249111Z","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-08-18T23:19:17.249744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-08-18T23:19:17.249819Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-08-18T23:19:17.255813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.797873ms"} {"level":"info","ts":"2026-08-18T23:19:17.277319Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-08-18T23:19:17.277393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-08-18T23:19:17.277412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-08-18T23:19:17.277421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-18T23:19:17.277432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-08-18T23:19:17.277467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-08-18T23:19:17.287384Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-18T23:19:17.288493Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-18T23:19:17.289627Z","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-08-18T23:19:17.291634Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-18T23:19:17.299512Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-18T23:19:17.299982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-08-18T23:19:17.299939Z","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-08-18T23:19:17.300102Z","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-08-18T23:19:17.300167Z","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-08-18T23:19:17.300151Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-18T23:19:17.300531Z","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-08-18T23:19:17.300754Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-18T23:19:17.300827Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-18T23:19:17.300974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-18T23:19:17.301047Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-18T23:19:18.1783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.178367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.178391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.178412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.178424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.178505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.17852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.182786Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.187996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:19:18.18802Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-18T23:19:18.188062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:19:18.188554Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-18T23:19:18.188603Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-18T23:19:18.190875Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.191011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.191053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.192063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-08-18T23:19:18.192214Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-18T23:19:22.534566Z","caller":"traceutil/trace.go:171","msg":"trace[431803749] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"205.253996ms","start":"2026-08-18T23:19:22.329271Z","end":"2026-08-18T23:19:22.534525Z","steps":["trace[431803749] 'process raft request' (duration: 126.897563ms)","trace[431803749] 'compare' (duration: 78.074117ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:23.129638Z","caller":"traceutil/trace.go:171","msg":"trace[1749367215] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"117.937661ms","start":"2026-08-18T23:19:23.011643Z","end":"2026-08-18T23:19:23.12958Z","steps":["trace[1749367215] 'process raft request' (duration: 49.032019ms)","trace[1749367215] 'compare' (duration: 68.631286ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:23.396334Z","caller":"traceutil/trace.go:171","msg":"trace[180675733] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"109.769385ms","start":"2026-08-18T23:19:23.286541Z","end":"2026-08-18T23:19:23.39631Z","steps":["trace[180675733] 'process raft request' (duration: 103.405627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:27.164611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.787351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:19:27.164869Z","caller":"traceutil/trace.go:171","msg":"trace[1871893504] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"132.226799ms","start":"2026-08-18T23:19:27.032608Z","end":"2026-08-18T23:19:27.164834Z","steps":["trace[1871893504] 'process raft request' (duration: 132.160348ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:27.164923Z","caller":"traceutil/trace.go:171","msg":"trace[1030856736] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"133.501394ms","start":"2026-08-18T23:19:27.031382Z","end":"2026-08-18T23:19:27.164883Z","steps":["trace[1030856736] 'compare' (duration: 129.615528ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:27.354596Z","caller":"traceutil/trace.go:171","msg":"trace[1404505064] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:323; }","duration":"295.641099ms","start":"2026-08-18T23:19:27.05892Z","end":"2026-08-18T23:19:27.354561Z","steps":["trace[1404505064] 'read index received' (duration: 105.268031ms)","trace[1404505064] 'applied index is now lower than readState.Index' (duration: 190.371098ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:27.354702Z","caller":"traceutil/trace.go:171","msg":"trace[894737316] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"317.710332ms","start":"2026-08-18T23:19:27.036958Z","end":"2026-08-18T23:19:27.354669Z","steps":["trace[894737316] 'process raft request' (duration: 267.497049ms)","trace[894737316] 'compare' (duration: 49.898787ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:27.354985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.936685ms","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-08-18T23:19:27.355064Z","caller":"traceutil/trace.go:171","msg":"trace[1718578234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:312; }","duration":"296.1816ms","start":"2026-08-18T23:19:27.058859Z","end":"2026-08-18T23:19:27.35504Z","steps":["trace[1718578234] 'agreement among raft nodes before linearized reading' (duration: 295.921555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:27.355116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:27.036935Z","time spent":"317.842185ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4411,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:27.355181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.54623ms","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-08-18T23:19:27.355266Z","caller":"traceutil/trace.go:171","msg":"trace[1987474585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:312; }","duration":"146.704073ms","start":"2026-08-18T23:19:27.208543Z","end":"2026-08-18T23:19:27.355247Z","steps":["trace[1987474585] 'agreement among raft nodes before linearized reading' (duration: 146.503439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:27.355028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.017208ms","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-08-18T23:19:27.355322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.351887ms","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-08-18T23:19:27.355324Z","caller":"traceutil/trace.go:171","msg":"trace[1787124054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:312; }","duration":"246.405456ms","start":"2026-08-18T23:19:27.108902Z","end":"2026-08-18T23:19:27.355308Z","steps":["trace[1787124054] 'agreement among raft nodes before linearized reading' (duration: 246.003708ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:27.355366Z","caller":"traceutil/trace.go:171","msg":"trace[876678246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:312; }","duration":"194.443958ms","start":"2026-08-18T23:19:27.160909Z","end":"2026-08-18T23:19:27.355353Z","steps":["trace[876678246] 'agreement among raft nodes before linearized reading' (duration: 194.352546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:28.360192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.568933ms","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-08-18T23:19:28.360385Z","caller":"traceutil/trace.go:171","msg":"trace[164170899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:343; }","duration":"300.818018ms","start":"2026-08-18T23:19:28.059544Z","end":"2026-08-18T23:19:28.360362Z","steps":["trace[164170899] 'range keys from in-memory index tree' (duration: 300.42164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:28.360541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:28.059522Z","time spent":"301.004191ms","remote":"127.0.0.1:50200","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":"info","ts":"2026-08-18T23:19:28.52905Z","caller":"traceutil/trace.go:171","msg":"trace[1363689710] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"163.233343ms","start":"2026-08-18T23:19:28.365789Z","end":"2026-08-18T23:19:28.529022Z","steps":["trace[1363689710] 'process raft request' (duration: 163.08434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:28.775024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.146565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-18T23:19:28.775049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.249438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:19:28.775097Z","caller":"traceutil/trace.go:171","msg":"trace[546557913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:344; }","duration":"138.338559ms","start":"2026-08-18T23:19:28.636746Z","end":"2026-08-18T23:19:28.775085Z","steps":["trace[546557913] 'range keys from in-memory index tree' (duration: 138.156645ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:28.775095Z","caller":"traceutil/trace.go:171","msg":"trace[1274524018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:344; }","duration":"157.271487ms","start":"2026-08-18T23:19:28.617807Z","end":"2026-08-18T23:19:28.775078Z","steps":["trace[1274524018] 'range keys from in-memory index tree' (duration: 157.033832ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:45.253948Z","caller":"traceutil/trace.go:171","msg":"trace[1805498526] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"376.408563ms","start":"2026-08-18T23:19:44.877524Z","end":"2026-08-18T23:19:45.253932Z","steps":["trace[1805498526] 'read index received' (duration: 376.276301ms)","trace[1805498526] 'applied index is now lower than readState.Index' (duration: 131.752µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:45.253994Z","caller":"traceutil/trace.go:171","msg":"trace[1374675378] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"648.746342ms","start":"2026-08-18T23:19:44.605222Z","end":"2026-08-18T23:19:45.253969Z","steps":["trace[1374675378] 'process raft request' (duration: 648.61409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:45.254058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.530783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:19:45.254117Z","caller":"traceutil/trace.go:171","msg":"trace[1097099788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"373.619454ms","start":"2026-08-18T23:19:44.88049Z","end":"2026-08-18T23:19:45.254109Z","steps":["trace[1097099788] 'agreement among raft nodes before linearized reading' (duration: 373.533873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:45.254137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:44.880481Z","time spent":"373.650985ms","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-18T23:19:45.25414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:44.605205Z","time spent":"648.841143ms","remote":"127.0.0.1:50200","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-08-18T23:19:45.254154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.601897ms","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-08-18T23:19:45.25431Z","caller":"traceutil/trace.go:171","msg":"trace[1502551269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"376.804449ms","start":"2026-08-18T23:19:44.87748Z","end":"2026-08-18T23:19:45.254285Z","steps":["trace[1502551269] 'agreement among raft nodes before linearized reading' (duration: 376.534155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:45.254377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:44.877463Z","time spent":"376.905102ms","remote":"127.0.0.1:50200","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-08-18T23:19:45.485331Z","caller":"traceutil/trace.go:171","msg":"trace[963003478] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"228.110263ms","start":"2026-08-18T23:19:45.2572Z","end":"2026-08-18T23:19:45.48531Z","steps":["trace[963003478] 'process raft request' (duration: 225.749863ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:45.486427Z","caller":"traceutil/trace.go:171","msg":"trace[1037998867] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"228.189596ms","start":"2026-08-18T23:19:45.25822Z","end":"2026-08-18T23:19:45.48641Z","steps":["trace[1037998867] 'process raft request' (duration: 227.962952ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:47.470629Z","caller":"traceutil/trace.go:171","msg":"trace[2122141448] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"208.355207ms","start":"2026-08-18T23:19:47.262244Z","end":"2026-08-18T23:19:47.4706Z","steps":["trace[2122141448] 'process raft request' (duration: 208.181054ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.046258Z","caller":"traceutil/trace.go:171","msg":"trace[21970792] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"244.591625ms","start":"2026-08-18T23:19:55.801639Z","end":"2026-08-18T23:19:56.046231Z","steps":["trace[21970792] 'read index received' (duration: 244.401612ms)","trace[21970792] 'applied index is now lower than readState.Index' (duration: 188.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:56.046272Z","caller":"traceutil/trace.go:171","msg":"trace[537681748] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"292.410434ms","start":"2026-08-18T23:19:55.753834Z","end":"2026-08-18T23:19:56.046245Z","steps":["trace[537681748] 'process raft request' (duration: 292.230131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.046497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.835299ms","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-08-18T23:19:56.046544Z","caller":"traceutil/trace.go:171","msg":"trace[2035203586] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:486; }","duration":"244.937491ms","start":"2026-08-18T23:19:55.801595Z","end":"2026-08-18T23:19:56.046532Z","steps":["trace[2035203586] 'agreement among raft nodes before linearized reading' (duration: 244.781629ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.177771Z","caller":"traceutil/trace.go:171","msg":"trace[597801432] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"117.192837ms","start":"2026-08-18T23:19:56.060552Z","end":"2026-08-18T23:19:56.177745Z","steps":["trace[597801432] 'read index received' (duration: 116.734389ms)","trace[597801432] 'applied index is now lower than readState.Index' (duration: 457.208µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:56.177829Z","caller":"traceutil/trace.go:171","msg":"trace[262191175] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"127.480893ms","start":"2026-08-18T23:19:56.050328Z","end":"2026-08-18T23:19:56.177809Z","steps":["trace[262191175] 'process raft request' (duration: 127.021085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.1779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.318239ms","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-08-18T23:19:56.177959Z","caller":"traceutil/trace.go:171","msg":"trace[759295836] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:487; }","duration":"117.411439ms","start":"2026-08-18T23:19:56.060514Z","end":"2026-08-18T23:19:56.177925Z","steps":["trace[759295836] 'agreement among raft nodes before linearized reading' (duration: 117.328138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.559891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.757109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-18T23:19:56.560848Z","caller":"traceutil/trace.go:171","msg":"trace[435516402] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"209.666362ms","start":"2026-08-18T23:19:56.351156Z","end":"2026-08-18T23:19:56.560822Z","steps":["trace[435516402] 'process raft request' (duration: 209.385257ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.560967Z","caller":"traceutil/trace.go:171","msg":"trace[1396262139] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"197.401454ms","start":"2026-08-18T23:19:56.363547Z","end":"2026-08-18T23:19:56.560948Z","steps":["trace[1396262139] 'process raft request' (duration: 197.352983ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.561068Z","caller":"traceutil/trace.go:171","msg":"trace[690239861] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"200.442913ms","start":"2026-08-18T23:19:56.360604Z","end":"2026-08-18T23:19:56.561047Z","steps":["trace[690239861] 'process raft request' (duration: 200.123238ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.561185Z","caller":"traceutil/trace.go:171","msg":"trace[1825333967] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"208.778518ms","start":"2026-08-18T23:19:56.352383Z","end":"2026-08-18T23:19:56.561162Z","steps":["trace[1825333967] 'process raft request' (duration: 208.212769ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.561189Z","caller":"traceutil/trace.go:171","msg":"trace[1526302830] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"210.17619ms","start":"2026-08-18T23:19:56.350969Z","end":"2026-08-18T23:19:56.561145Z","steps":["trace[1526302830] 'process raft request' (duration: 209.438298ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.561243Z","caller":"traceutil/trace.go:171","msg":"trace[1869085330] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"198.503731ms","start":"2026-08-18T23:19:56.36273Z","end":"2026-08-18T23:19:56.561234Z","steps":["trace[1869085330] 'process raft request' (duration: 198.105985ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.561255Z","caller":"traceutil/trace.go:171","msg":"trace[675900973] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"201.863616ms","start":"2026-08-18T23:19:56.35938Z","end":"2026-08-18T23:19:56.561244Z","steps":["trace[675900973] 'process raft request' (duration: 201.284956ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.56125Z","caller":"traceutil/trace.go:171","msg":"trace[1973773949] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"199.516928ms","start":"2026-08-18T23:19:56.361676Z","end":"2026-08-18T23:19:56.561193Z","steps":["trace[1973773949] 'process raft request' (duration: 199.105181ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.853515Z","caller":"traceutil/trace.go:171","msg":"trace[133788802] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"336.813107ms","start":"2026-08-18T23:19:56.516686Z","end":"2026-08-18T23:19:56.853499Z","steps":["trace[133788802] 'process raft request' (duration: 336.769687ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.853654Z","caller":"traceutil/trace.go:171","msg":"trace[1279868508] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"483.161435ms","start":"2026-08-18T23:19:56.370452Z","end":"2026-08-18T23:19:56.853614Z","steps":["trace[1279868508] 'process raft request' (duration: 482.81828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.853785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.370444Z","time spent":"483.280027ms","remote":"127.0.0.1:49970","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-08-18T23:19:56.853989Z","caller":"traceutil/trace.go:171","msg":"trace[296798564] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"473.688122ms","start":"2026-08-18T23:19:56.380293Z","end":"2026-08-18T23:19:56.853981Z","steps":["trace[296798564] 'process raft request' (duration: 473.071572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.854043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.380279Z","time spent":"473.733343ms","remote":"127.0.0.1:49970","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-08-18T23:19:56.854164Z","caller":"traceutil/trace.go:171","msg":"trace[1202653268] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"446.617345ms","start":"2026-08-18T23:19:56.407536Z","end":"2026-08-18T23:19:56.854153Z","steps":["trace[1202653268] 'process raft request' (duration: 445.862392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.854206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.516661Z","time spent":"336.91352ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:19:56.854229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.407522Z","time spent":"446.678667ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:19:56.874597Z","caller":"traceutil/trace.go:171","msg":"trace[1511193518] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"289.38622ms","start":"2026-08-18T23:19:56.58519Z","end":"2026-08-18T23:19:56.874577Z","steps":["trace[1511193518] 'process raft request' (duration: 286.713947ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.874917Z","caller":"traceutil/trace.go:171","msg":"trace[164067695] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"300.44997ms","start":"2026-08-18T23:19:56.574458Z","end":"2026-08-18T23:19:56.874908Z","steps":["trace[164067695] 'process raft request' (duration: 296.385115ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.875116Z","caller":"traceutil/trace.go:171","msg":"trace[1439031074] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"302.459162ms","start":"2026-08-18T23:19:56.572646Z","end":"2026-08-18T23:19:56.875105Z","steps":["trace[1439031074] 'process raft request' (duration: 298.03397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.87522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.572628Z","time spent":"302.563343ms","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:19:56.875346Z","caller":"traceutil/trace.go:171","msg":"trace[1148895852] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"302.618315ms","start":"2026-08-18T23:19:56.572713Z","end":"2026-08-18T23:19:56.875331Z","steps":["trace[1148895852] 'process raft request' (duration: 298.055551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.875394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.572706Z","time spent":"302.665745ms","remote":"127.0.0.1:50422","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-08-18T23:19:56.875507Z","caller":"traceutil/trace.go:171","msg":"trace[1961087990] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"302.443152ms","start":"2026-08-18T23:19:56.573049Z","end":"2026-08-18T23:19:56.875492Z","steps":["trace[1961087990] 'process raft request' (duration: 297.760186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.875548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.57304Z","time spent":"302.484563ms","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:19:56.877669Z","caller":"traceutil/trace.go:171","msg":"trace[125319010] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"299.764779ms","start":"2026-08-18T23:19:56.577839Z","end":"2026-08-18T23:19:56.877604Z","steps":["trace[125319010] 'process raft request' (duration: 293.187593ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.877686Z","caller":"traceutil/trace.go:171","msg":"trace[1902832560] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"302.90221ms","start":"2026-08-18T23:19:56.574766Z","end":"2026-08-18T23:19:56.877668Z","steps":["trace[1902832560] 'process raft request' (duration: 296.128111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.875005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.57445Z","time spent":"300.491411ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:56.877745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.574756Z","time spent":"302.95818ms","remote":"127.0.0.1:49970","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-08-18T23:19:56.877957Z","caller":"traceutil/trace.go:171","msg":"trace[1291593794] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"296.060169ms","start":"2026-08-18T23:19:56.581855Z","end":"2026-08-18T23:19:56.877916Z","steps":["trace[1291593794] 'process raft request' (duration: 289.515923ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.878546Z","caller":"traceutil/trace.go:171","msg":"trace[1997906312] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"297.035374ms","start":"2026-08-18T23:19:56.580726Z","end":"2026-08-18T23:19:56.877761Z","steps":["trace[1997906312] 'process raft request' (duration: 290.59463ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.878706Z","caller":"traceutil/trace.go:171","msg":"trace[2144374486] transaction","detail":"{read_only:false; number_of_response:1; response_revision:504; }","duration":"302.074526ms","start":"2026-08-18T23:19:56.575888Z","end":"2026-08-18T23:19:56.877963Z","steps":["trace[2144374486] 'process raft request' (duration: 295.106824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.878757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:56.575881Z","time spent":"302.848819ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:19:56.879927Z","caller":"traceutil/trace.go:171","msg":"trace[1325577480] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"297.375849ms","start":"2026-08-18T23:19:56.58059Z","end":"2026-08-18T23:19:56.877966Z","steps":["trace[1325577480] 'process raft request' (duration: 290.480419ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:58.035471Z","caller":"traceutil/trace.go:171","msg":"trace[1227730241] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"254.189277ms","start":"2026-08-18T23:19:57.781249Z","end":"2026-08-18T23:19:58.035438Z","steps":["trace[1227730241] 'read index received' (duration: 252.690543ms)","trace[1227730241] 'applied index is now lower than readState.Index' (duration: 1.497914ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:58.035569Z","caller":"traceutil/trace.go:171","msg":"trace[1564835493] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"267.133194ms","start":"2026-08-18T23:19:57.768426Z","end":"2026-08-18T23:19:58.035559Z","steps":["trace[1564835493] 'process raft request' (duration: 266.231059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:58.035804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.477051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:19:58.035903Z","caller":"traceutil/trace.go:171","msg":"trace[45858652] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"254.636904ms","start":"2026-08-18T23:19:57.781244Z","end":"2026-08-18T23:19:58.035881Z","steps":["trace[45858652] 'agreement among raft nodes before linearized reading' (duration: 254.42062ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:58.045017Z","caller":"traceutil/trace.go:171","msg":"trace[1566497158] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"236.598363ms","start":"2026-08-18T23:19:57.808396Z","end":"2026-08-18T23:19:58.044995Z","steps":["trace[1566497158] 'process raft request' (duration: 236.3915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:58.045267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.447242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-6p5tf\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-08-18T23:19:58.045326Z","caller":"traceutil/trace.go:171","msg":"trace[1440374763] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-6p5tf; range_end:; response_count:1; response_revision:583; }","duration":"169.556364ms","start":"2026-08-18T23:19:57.875756Z","end":"2026-08-18T23:19:58.045313Z","steps":["trace[1440374763] 'agreement among raft nodes before linearized reading' (duration: 169.201309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:58.808434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342613,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-18T23:19:59.115575Z","caller":"traceutil/trace.go:171","msg":"trace[996382951] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"836.971787ms","start":"2026-08-18T23:19:58.278579Z","end":"2026-08-18T23:19:59.115551Z","steps":["trace[996382951] 'process raft request' (duration: 836.594621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.115957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.278561Z","time spent":"837.085228ms","remote":"127.0.0.1:49970","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-08-18T23:19:59.12175Z","caller":"traceutil/trace.go:171","msg":"trace[1083942168] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"771.445007ms","start":"2026-08-18T23:19:58.350291Z","end":"2026-08-18T23:19:59.121736Z","steps":["trace[1083942168] 'process raft request' (duration: 771.200223ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:59.121805Z","caller":"traceutil/trace.go:171","msg":"trace[456294017] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"336.207646ms","start":"2026-08-18T23:19:58.785568Z","end":"2026-08-18T23:19:59.121776Z","steps":["trace[456294017] 'process raft request' (duration: 336.078524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.12194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.785535Z","time spent":"336.335918ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:19:59.12197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.350257Z","time spent":"771.548139ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:59.122003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.473946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-08-18T23:19:59.122066Z","caller":"traceutil/trace.go:171","msg":"trace[1535719581] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:592; }","duration":"814.564348ms","start":"2026-08-18T23:19:58.30749Z","end":"2026-08-18T23:19:59.122054Z","steps":["trace[1535719581] 'agreement among raft nodes before linearized reading' (duration: 814.388695ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:59.122107Z","caller":"traceutil/trace.go:171","msg":"trace[886307679] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"814.152181ms","start":"2026-08-18T23:19:58.307523Z","end":"2026-08-18T23:19:59.121675Z","steps":["trace[886307679] 'read index received' (duration: 808.360429ms)","trace[886307679] 'applied index is now lower than readState.Index' (duration: 5.790952ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:59.122116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.307477Z","time spent":"814.630919ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31148,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-18T23:19:59.12232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.845998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:19:59.122352Z","caller":"traceutil/trace.go:171","msg":"trace[1449888568] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"740.901478ms","start":"2026-08-18T23:19:58.381441Z","end":"2026-08-18T23:19:59.122342Z","steps":["trace[1449888568] 'agreement among raft nodes before linearized reading' (duration: 740.853848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.122395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.381384Z","time spent":"741.00494ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-18T23:19:59.12241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.317374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-08-18T23:19:59.122465Z","caller":"traceutil/trace.go:171","msg":"trace[353343476] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"140.405085ms","start":"2026-08-18T23:19:58.98205Z","end":"2026-08-18T23:19:59.122455Z","steps":["trace[353343476] 'agreement among raft nodes before linearized reading' (duration: 140.289803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.122536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.848513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:19:59.122589Z","caller":"traceutil/trace.go:171","msg":"trace[770493218] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"539.939744ms","start":"2026-08-18T23:19:58.582635Z","end":"2026-08-18T23:19:59.122575Z","steps":["trace[770493218] 'agreement among raft nodes before linearized reading' (duration: 539.851783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.122619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.061926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:19:59.122629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.582617Z","time spent":"540.004666ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-18T23:19:59.122641Z","caller":"traceutil/trace.go:171","msg":"trace[1294179912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"660.124957ms","start":"2026-08-18T23:19:58.462509Z","end":"2026-08-18T23:19:59.122634Z","steps":["trace[1294179912] 'agreement among raft nodes before linearized reading' (duration: 660.072286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:59.122663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:58.462488Z","time spent":"660.171927ms","remote":"127.0.0.1:49904","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-08-18T23:20:04.30856Z","caller":"traceutil/trace.go:171","msg":"trace[1274352678] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"335.458566ms","start":"2026-08-18T23:20:03.973077Z","end":"2026-08-18T23:20:04.308536Z","steps":["trace[1274352678] 'read index received' (duration: 333.11675ms)","trace[1274352678] 'applied index is now lower than readState.Index' (duration: 2.340926ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:04.308689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.602988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:04.308719Z","caller":"traceutil/trace.go:171","msg":"trace[1015803418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"335.68415ms","start":"2026-08-18T23:20:03.973025Z","end":"2026-08-18T23:20:04.308709Z","steps":["trace[1015803418] 'agreement among raft nodes before linearized reading' (duration: 335.601048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:04.308749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.973006Z","time spent":"335.73701ms","remote":"127.0.0.1:49904","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-08-18T23:20:04.309416Z","caller":"traceutil/trace.go:171","msg":"trace[336497967] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"414.187791ms","start":"2026-08-18T23:20:03.895171Z","end":"2026-08-18T23:20:04.309359Z","steps":["trace[336497967] 'process raft request' (duration: 411.756713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:04.309654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.895148Z","time spent":"414.397444ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:04.467245Z","caller":"traceutil/trace.go:171","msg":"trace[1167834740] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"105.159248ms","start":"2026-08-18T23:20:04.362063Z","end":"2026-08-18T23:20:04.467223Z","steps":["trace[1167834740] 'process raft request' (duration: 93.047781ms)","trace[1167834740] 'compare' (duration: 11.63041ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:04.887826Z","caller":"traceutil/trace.go:171","msg":"trace[287733695] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"104.250223ms","start":"2026-08-18T23:20:04.783549Z","end":"2026-08-18T23:20:04.887799Z","steps":["trace[287733695] 'process raft request' (duration: 104.102891ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:05.087052Z","caller":"traceutil/trace.go:171","msg":"trace[1629479908] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"212.221608ms","start":"2026-08-18T23:20:04.874796Z","end":"2026-08-18T23:20:05.087017Z","steps":["trace[1629479908] 'process raft request' (duration: 211.590288ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:05.251521Z","caller":"traceutil/trace.go:171","msg":"trace[1334906463] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"109.541217ms","start":"2026-08-18T23:20:05.141958Z","end":"2026-08-18T23:20:05.251499Z","steps":["trace[1334906463] 'process raft request' (duration: 52.764443ms)","trace[1334906463] 'compare' (duration: 56.662592ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:05.800478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.285941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:05.800595Z","caller":"traceutil/trace.go:171","msg":"trace[879530270] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"361.514397ms","start":"2026-08-18T23:20:05.439063Z","end":"2026-08-18T23:20:05.800577Z","steps":["trace[879530270] 'read index received' (duration: 57.911752ms)","trace[879530270] 'applied index is now lower than readState.Index' (duration: 303.601205ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:05.80069Z","caller":"traceutil/trace.go:171","msg":"trace[468657762] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"532.241616ms","start":"2026-08-18T23:20:05.268438Z","end":"2026-08-18T23:20:05.80068Z","steps":["trace[468657762] 'process raft request' (duration: 228.652732ms)","trace[468657762] 'compare' (duration: 302.964045ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:05.800746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:05.268419Z","time spent":"532.304067ms","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:20:05.800894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.042554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:05.800978Z","caller":"traceutil/trace.go:171","msg":"trace[1794330245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"338.161446ms","start":"2026-08-18T23:20:05.462803Z","end":"2026-08-18T23:20:05.800965Z","steps":["trace[1794330245] 'agreement among raft nodes before linearized reading' (duration: 337.913533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:05.801001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:05.46279Z","time spent":"338.206237ms","remote":"127.0.0.1:49904","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-08-18T23:20:05.801286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.935353ms","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-08-18T23:20:05.801329Z","caller":"traceutil/trace.go:171","msg":"trace[755678794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"125.021255ms","start":"2026-08-18T23:20:05.676297Z","end":"2026-08-18T23:20:05.801318Z","steps":["trace[755678794] 'agreement among raft nodes before linearized reading' (duration: 124.867042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:05.801291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.784312ms","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-08-18T23:20:05.801359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.502768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:20:05.801506Z","caller":"traceutil/trace.go:171","msg":"trace[1162077577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"178.65016ms","start":"2026-08-18T23:20:05.622805Z","end":"2026-08-18T23:20:05.801455Z","steps":["trace[1162077577] 'agreement among raft nodes before linearized reading' (duration: 178.370616ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:05.801529Z","caller":"traceutil/trace.go:171","msg":"trace[343725747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"189.951774ms","start":"2026-08-18T23:20:05.611466Z","end":"2026-08-18T23:20:05.801418Z","steps":["trace[343725747] 'agreement among raft nodes before linearized reading' (duration: 189.711571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:05.801887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.788237ms","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-08-18T23:20:05.801996Z","caller":"traceutil/trace.go:171","msg":"trace[1062265349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"362.96264ms","start":"2026-08-18T23:20:05.439007Z","end":"2026-08-18T23:20:05.80197Z","steps":["trace[1062265349] 'agreement among raft nodes before linearized reading' (duration: 362.308059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:05.802063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:05.438985Z","time spent":"363.067641ms","remote":"127.0.0.1:50200","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-08-18T23:20:05.972948Z","caller":"traceutil/trace.go:171","msg":"trace[944258722] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"167.208155ms","start":"2026-08-18T23:20:05.805705Z","end":"2026-08-18T23:20:05.972913Z","steps":["trace[944258722] 'process raft request' (duration: 159.022579ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027338Z","caller":"traceutil/trace.go:171","msg":"trace[1689491020] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"220.774298ms","start":"2026-08-18T23:20:05.806526Z","end":"2026-08-18T23:20:06.027301Z","steps":["trace[1689491020] 'process raft request' (duration: 220.561685ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027493Z","caller":"traceutil/trace.go:171","msg":"trace[1201732259] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"216.344309ms","start":"2026-08-18T23:20:05.811126Z","end":"2026-08-18T23:20:06.02747Z","steps":["trace[1201732259] 'process raft request' (duration: 216.301969ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027747Z","caller":"traceutil/trace.go:171","msg":"trace[59028448] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"218.854388ms","start":"2026-08-18T23:20:05.808882Z","end":"2026-08-18T23:20:06.027736Z","steps":["trace[59028448] 'process raft request' (duration: 218.423972ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027829Z","caller":"traceutil/trace.go:171","msg":"trace[1064966311] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"220.86132ms","start":"2026-08-18T23:20:05.806955Z","end":"2026-08-18T23:20:06.027816Z","steps":["trace[1064966311] 'process raft request' (duration: 220.24359ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.02784Z","caller":"traceutil/trace.go:171","msg":"trace[150420775] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"220.324871ms","start":"2026-08-18T23:20:05.807482Z","end":"2026-08-18T23:20:06.027807Z","steps":["trace[150420775] 'process raft request' (duration: 219.753862ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027941Z","caller":"traceutil/trace.go:171","msg":"trace[420522099] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"217.667619ms","start":"2026-08-18T23:20:05.810268Z","end":"2026-08-18T23:20:06.027936Z","steps":["trace[420522099] 'process raft request' (duration: 217.081871ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.027913Z","caller":"traceutil/trace.go:171","msg":"trace[1269530076] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"216.821947ms","start":"2026-08-18T23:20:05.81108Z","end":"2026-08-18T23:20:06.027902Z","steps":["trace[1269530076] 'process raft request' (duration: 216.307949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.853919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:07.354497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:07.855285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:08.35599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:08.595754Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.253844375s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-18T23:20:09.461026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000364527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-18T23:20:09.461107Z","caller":"traceutil/trace.go:171","msg":"trace[965233837] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000469399s","start":"2026-08-18T23:20:07.460621Z","end":"2026-08-18T23:20:09.46109Z","steps":["trace[965233837] 'agreement among raft nodes before linearized reading' (duration: 2.000360417s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:09.461148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:07.460531Z","time spent":"2.000609192s","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-18T23:20:10.244649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648707836s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-18T23:20:10.244747Z","caller":"traceutil/trace.go:171","msg":"trace[1537773736] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"3.891709565s","start":"2026-08-18T23:20:06.353018Z","end":"2026-08-18T23:20:10.244728Z","steps":["trace[1537773736] 'read index received' (duration: 2.242869197s)","trace[1537773736] 'applied index is now lower than readState.Index' (duration: 1.648839018s)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:10.244943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.891926108s","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-08-18T23:20:10.244969Z","caller":"traceutil/trace.go:171","msg":"trace[1495866838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"3.891954889s","start":"2026-08-18T23:20:06.353005Z","end":"2026-08-18T23:20:10.24496Z","steps":["trace[1495866838] 'agreement among raft nodes before linearized reading' (duration: 3.891800926s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.244913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.211693ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:10.245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:06.352961Z","time spent":"3.89203268s","remote":"127.0.0.1:50200","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-08-18T23:20:10.24502Z","caller":"traceutil/trace.go:171","msg":"trace[1197976650] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:660; }","duration":"318.316174ms","start":"2026-08-18T23:20:09.926682Z","end":"2026-08-18T23:20:10.244998Z","steps":["trace[1197976650] 'range keys from in-memory index tree' (duration: 318.200573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.745243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342785,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-18T23:20:10.844413Z","caller":"traceutil/trace.go:171","msg":"trace[1683141474] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"640.475085ms","start":"2026-08-18T23:20:10.203914Z","end":"2026-08-18T23:20:10.844389Z","steps":["trace[1683141474] 'process raft request' (duration: 640.322633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.844565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.203891Z","time spent":"640.596306ms","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:10.872289Z","caller":"traceutil/trace.go:171","msg":"trace[452918253] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"627.493849ms","start":"2026-08-18T23:20:10.244772Z","end":"2026-08-18T23:20:10.872266Z","steps":["trace[452918253] 'read index received' (duration: 599.52126ms)","trace[452918253] 'applied index is now lower than readState.Index' (duration: 27.971669ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:10.87263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.973274571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2646r\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-18T23:20:10.872657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.8390696s","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-08-18T23:20:10.872677Z","caller":"traceutil/trace.go:171","msg":"trace[1630680874] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2646r; range_end:; response_count:1; response_revision:662; }","duration":"3.973426184s","start":"2026-08-18T23:20:06.899239Z","end":"2026-08-18T23:20:10.872666Z","steps":["trace[1630680874] 'agreement among raft nodes before linearized reading' (duration: 3.97311963s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.872717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:06.899218Z","time spent":"3.973490335s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-2646r\" "} {"level":"info","ts":"2026-08-18T23:20:10.872714Z","caller":"traceutil/trace.go:171","msg":"trace[1323317584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"2.839173312s","start":"2026-08-18T23:20:08.033524Z","end":"2026-08-18T23:20:10.872698Z","steps":["trace[1323317584] 'agreement among raft nodes before linearized reading' (duration: 2.838996819s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.872762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.033494Z","time spent":"2.839256512s","remote":"127.0.0.1:50200","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-08-18T23:20:10.872893Z","caller":"traceutil/trace.go:171","msg":"trace[107876441] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"624.658087ms","start":"2026-08-18T23:20:10.248214Z","end":"2026-08-18T23:20:10.872872Z","steps":["trace[107876441] 'process raft request' (duration: 603.153124ms)","trace[107876441] 'compare' (duration: 20.748252ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:10.872915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.841392085s","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-08-18T23:20:10.872934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.894409981s","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-08-18T23:20:10.872969Z","caller":"traceutil/trace.go:171","msg":"trace[2108866797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"2.841496345s","start":"2026-08-18T23:20:08.031457Z","end":"2026-08-18T23:20:10.872954Z","steps":["trace[2108866797] 'agreement among raft nodes before linearized reading' (duration: 2.841335273s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:10.872977Z","caller":"traceutil/trace.go:171","msg":"trace[941874832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"2.894488902s","start":"2026-08-18T23:20:07.978475Z","end":"2026-08-18T23:20:10.872964Z","steps":["trace[941874832] 'agreement among raft nodes before linearized reading' (duration: 2.89436819s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.872976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.248193Z","time spent":"624.740328ms","remote":"127.0.0.1:50200","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-08-18T23:20:10.873013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:07.978428Z","time spent":"2.894576513s","remote":"127.0.0.1:50200","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-08-18T23:20:10.873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411690642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:10.873012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.031438Z","time spent":"2.841562537s","remote":"127.0.0.1:50200","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-08-18T23:20:10.873064Z","caller":"traceutil/trace.go:171","msg":"trace[680333332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"1.411806694s","start":"2026-08-18T23:20:09.461239Z","end":"2026-08-18T23:20:10.873046Z","steps":["trace[680333332] 'agreement among raft nodes before linearized reading' (duration: 1.411681772s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.873123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:09.46122Z","time spent":"1.411895925s","remote":"127.0.0.1:49910","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-08-18T23:20:10.873496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.840572242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:20:10.873533Z","caller":"traceutil/trace.go:171","msg":"trace[324110423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"2.840610033s","start":"2026-08-18T23:20:08.032912Z","end":"2026-08-18T23:20:10.873522Z","steps":["trace[324110423] 'agreement among raft nodes before linearized reading' (duration: 2.839906512s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.873561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.03282Z","time spent":"2.840735074s","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-18T23:20:11.897094Z","caller":"traceutil/trace.go:171","msg":"trace[1383932197] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.019087942s","start":"2026-08-18T23:20:10.877974Z","end":"2026-08-18T23:20:11.897062Z","steps":["trace[1383932197] 'process raft request' (duration: 934.525789ms)","trace[1383932197] 'compare' (duration: 83.842162ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:11.897275Z","caller":"traceutil/trace.go:171","msg":"trace[400204600] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.019045561s","start":"2026-08-18T23:20:10.878192Z","end":"2026-08-18T23:20:11.897238Z","steps":["trace[400204600] 'process raft request' (duration: 1.018608435s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.897327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.877835Z","time spent":"1.019412366s","remote":"127.0.0.1:49982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:20:11.897509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.878184Z","time spent":"1.019191634s","remote":"127.0.0.1:50200","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-08-18T23:20:11.897732Z","caller":"traceutil/trace.go:171","msg":"trace[518230274] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.019353657s","start":"2026-08-18T23:20:10.878333Z","end":"2026-08-18T23:20:11.897687Z","steps":["trace[518230274] 'process raft request' (duration: 1.018851508s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:11.897928Z","caller":"traceutil/trace.go:171","msg":"trace[2069499044] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:686; }","duration":"1.015680292s","start":"2026-08-18T23:20:10.882228Z","end":"2026-08-18T23:20:11.897908Z","steps":["trace[2069499044] 'read index received' (duration: 13.541194ms)","trace[2069499044] 'applied index is now lower than readState.Index' (duration: 1.002137908s)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:11.897922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.878326Z","time spent":"1.019487379s","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:11.897931Z","caller":"traceutil/trace.go:171","msg":"trace[1969453451] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.018394903s","start":"2026-08-18T23:20:10.879488Z","end":"2026-08-18T23:20:11.897883Z","steps":["trace[1969453451] 'process raft request' (duration: 1.018084458s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.898086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.879481Z","time spent":"1.018522574s","remote":"127.0.0.1:50200","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-08-18T23:20:11.898159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015924855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:11.898089Z","caller":"traceutil/trace.go:171","msg":"trace[1822571292] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.01024584s","start":"2026-08-18T23:20:10.887813Z","end":"2026-08-18T23:20:11.898058Z","steps":["trace[1822571292] 'process raft request' (duration: 1.009932276s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:11.898217Z","caller":"traceutil/trace.go:171","msg":"trace[671586720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"1.016005046s","start":"2026-08-18T23:20:10.882198Z","end":"2026-08-18T23:20:11.898203Z","steps":["trace[671586720] 'agreement among raft nodes before linearized reading' (duration: 1.015915865s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.898245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.887803Z","time spent":"1.010415913s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:11.89829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:10.882184Z","time spent":"1.016093737s","remote":"127.0.0.1:49904","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-08-18T23:20:11.898553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.193145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-18T23:20:11.898616Z","caller":"traceutil/trace.go:171","msg":"trace[2130713483] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:668; }","duration":"770.297646ms","start":"2026-08-18T23:20:11.128303Z","end":"2026-08-18T23:20:11.898601Z","steps":["trace[2130713483] 'agreement among raft nodes before linearized reading' (duration: 770.047622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.898655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:11.128281Z","time spent":"770.364708ms","remote":"127.0.0.1:49948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-08-18T23:20:13.399416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:13.900197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:14.405764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:14.905894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958342804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:15.259519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.37782621s","expected-duration":"1s"} {"level":"info","ts":"2026-08-18T23:20:15.261721Z","caller":"traceutil/trace.go:171","msg":"trace[1145619752] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"2.380097432s","start":"2026-08-18T23:20:12.881601Z","end":"2026-08-18T23:20:15.261698Z","steps":["trace[1145619752] 'process raft request' (duration: 2.37997426s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.261863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:12.88158Z","time spent":"2.380208275s","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:15.262657Z","caller":"traceutil/trace.go:171","msg":"trace[567683822] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:695; }","duration":"2.363676939s","start":"2026-08-18T23:20:12.898955Z","end":"2026-08-18T23:20:15.262632Z","steps":["trace[567683822] 'read index received' (duration: 2.363671559s)","trace[567683822] 'applied index is now lower than readState.Index' (duration: 4.42µs)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:15.262819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.363856932s","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-08-18T23:20:15.262856Z","caller":"traceutil/trace.go:171","msg":"trace[864119475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"2.363922432s","start":"2026-08-18T23:20:12.898925Z","end":"2026-08-18T23:20:15.262847Z","steps":["trace[864119475] 'agreement among raft nodes before linearized reading' (duration: 2.36379091s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.262885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:12.898912Z","time spent":"2.363965963s","remote":"127.0.0.1:50200","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-08-18T23:20:15.462849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999922431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-18T23:20:15.462947Z","caller":"traceutil/trace.go:171","msg":"trace[1485068608] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000063903s","start":"2026-08-18T23:20:13.462865Z","end":"2026-08-18T23:20:15.462929Z","steps":["trace[1485068608] 'agreement among raft nodes before linearized reading' (duration: 1.999954712s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.463005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:13.462845Z","time spent":"2.000150784s","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/18 23:20:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-08-18T23:20:15.605863Z","caller":"traceutil/trace.go:171","msg":"trace[765430899] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"343.144258ms","start":"2026-08-18T23:20:15.262695Z","end":"2026-08-18T23:20:15.605839Z","steps":["trace[765430899] 'read index received' (duration: 338.317638ms)","trace[765430899] 'applied index is now lower than readState.Index' (duration: 4.82564ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:15.605924Z","caller":"traceutil/trace.go:171","msg":"trace[1914622769] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"698.099937ms","start":"2026-08-18T23:20:14.907801Z","end":"2026-08-18T23:20:15.605901Z","steps":["trace[1914622769] 'process raft request' (duration: 693.207946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.60604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696451561s","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-08-18T23:20:15.606039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:14.907784Z","time spent":"698.185408ms","remote":"127.0.0.1:50200","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-08-18T23:20:15.606076Z","caller":"traceutil/trace.go:171","msg":"trace[1970962736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.696541693s","start":"2026-08-18T23:20:13.909526Z","end":"2026-08-18T23:20:15.606068Z","steps":["trace[1970962736] 'agreement among raft nodes before linearized reading' (duration: 1.696417421s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.606106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:13.9095Z","time spent":"1.696599404s","remote":"127.0.0.1:50200","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-08-18T23:20:15.606225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697209452s","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-08-18T23:20:15.606255Z","caller":"traceutil/trace.go:171","msg":"trace[588060544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.697266263s","start":"2026-08-18T23:20:13.908982Z","end":"2026-08-18T23:20:15.606248Z","steps":["trace[588060544] 'agreement among raft nodes before linearized reading' (duration: 1.697190972s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.606246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696776416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-18T23:20:15.60628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:13.908965Z","time spent":"1.697308475s","remote":"127.0.0.1:50200","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-08-18T23:20:15.60631Z","caller":"traceutil/trace.go:171","msg":"trace[929097763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.696882298s","start":"2026-08-18T23:20:13.90941Z","end":"2026-08-18T23:20:15.606292Z","steps":["trace[929097763] 'agreement among raft nodes before linearized reading' (duration: 1.696647564s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.606454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:13.909392Z","time spent":"1.69699419s","remote":"127.0.0.1:50200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-18T23:20:15.663899Z","caller":"traceutil/trace.go:171","msg":"trace[1369011895] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"397.470025ms","start":"2026-08-18T23:20:15.266413Z","end":"2026-08-18T23:20:15.663883Z","steps":["trace[1369011895] 'process raft request' (duration: 397.379663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.664019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:15.266395Z","time spent":"397.571727ms","remote":"127.0.0.1:50200","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-08-18T23:20:15.664246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.115438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:15.664284Z","caller":"traceutil/trace.go:171","msg":"trace[349459053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"201.173268ms","start":"2026-08-18T23:20:15.4631Z","end":"2026-08-18T23:20:15.664273Z","steps":["trace[349459053] 'agreement among raft nodes before linearized reading' (duration: 201.116827ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:16.045732Z","caller":"traceutil/trace.go:171","msg":"trace[1503922328] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"107.013656ms","start":"2026-08-18T23:20:15.938692Z","end":"2026-08-18T23:20:16.045705Z","steps":["trace[1503922328] 'process raft request' (duration: 106.034872ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:16.239116Z","caller":"traceutil/trace.go:171","msg":"trace[1350576059] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"114.366037ms","start":"2026-08-18T23:20:16.124723Z","end":"2026-08-18T23:20:16.239089Z","steps":["trace[1350576059] 'process raft request' (duration: 61.179362ms)","trace[1350576059] 'compare' (duration: 53.063584ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:16.46003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.215413ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-18T23:20:17.186815Z","caller":"traceutil/trace.go:171","msg":"trace[342357883] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"254.48641ms","start":"2026-08-18T23:20:16.932305Z","end":"2026-08-18T23:20:17.186792Z","steps":["trace[342357883] 'process raft request' (duration: 254.351048ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:17.265707Z","caller":"traceutil/trace.go:171","msg":"trace[1530790809] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"317.23933ms","start":"2026-08-18T23:20:16.948426Z","end":"2026-08-18T23:20:17.265665Z","steps":["trace[1530790809] 'process raft request' (duration: 316.932775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:17.266191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:16.94839Z","time spent":"317.440362ms","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:27.178474Z","caller":"traceutil/trace.go:171","msg":"trace[1592419768] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"156.16212ms","start":"2026-08-18T23:20:27.022268Z","end":"2026-08-18T23:20:27.17843Z","steps":["trace[1592419768] 'read index received' (duration: 155.973168ms)","trace[1592419768] 'applied index is now lower than readState.Index' (duration: 188.212µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:27.178522Z","caller":"traceutil/trace.go:171","msg":"trace[774020905] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"161.096509ms","start":"2026-08-18T23:20:27.017399Z","end":"2026-08-18T23:20:27.178496Z","steps":["trace[774020905] 'process raft request' (duration: 160.877106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:27.178649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:27.178721Z","caller":"traceutil/trace.go:171","msg":"trace[1225707592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:742; }","duration":"156.473986ms","start":"2026-08-18T23:20:27.02223Z","end":"2026-08-18T23:20:27.178704Z","steps":["trace[1225707592] 'agreement among raft nodes before linearized reading' (duration: 156.344693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:27.178921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.515536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:27.178974Z","caller":"traceutil/trace.go:171","msg":"trace[1604782106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:742; }","duration":"156.632217ms","start":"2026-08-18T23:20:27.022329Z","end":"2026-08-18T23:20:27.178961Z","steps":["trace[1604782106] 'agreement among raft nodes before linearized reading' (duration: 156.528106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:27.179445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.215558ms","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-08-18T23:20:27.179473Z","caller":"traceutil/trace.go:171","msg":"trace[1197721352] 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:742; }","duration":"132.263818ms","start":"2026-08-18T23:20:27.047202Z","end":"2026-08-18T23:20:27.179466Z","steps":["trace[1197721352] 'agreement among raft nodes before linearized reading' (duration: 132.221728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:27.179582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.0351ms","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-08-18T23:20:27.179605Z","caller":"traceutil/trace.go:171","msg":"trace[2057298953] 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:742; }","duration":"133.079671ms","start":"2026-08-18T23:20:27.04652Z","end":"2026-08-18T23:20:27.179599Z","steps":["trace[2057298953] 'agreement among raft nodes before linearized reading' (duration: 133.03939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:27.179699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.183412ms","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\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:27.179721Z","caller":"traceutil/trace.go:171","msg":"trace[1096632265] 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:742; }","duration":"133.217773ms","start":"2026-08-18T23:20:27.046499Z","end":"2026-08-18T23:20:27.179716Z","steps":["trace[1096632265] 'agreement among raft nodes before linearized reading' (duration: 133.172112ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:29.250854Z","caller":"traceutil/trace.go:171","msg":"trace[1263976643] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"230.303145ms","start":"2026-08-18T23:20:29.020524Z","end":"2026-08-18T23:20:29.250827Z","steps":["trace[1263976643] 'process raft request' (duration: 230.110243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:32.963944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342822958343111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:33.463149Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.427321849s","expected-duration":"1s"} {"level":"info","ts":"2026-08-18T23:20:33.46348Z","caller":"traceutil/trace.go:171","msg":"trace[149300075] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"999.815622ms","start":"2026-08-18T23:20:32.463641Z","end":"2026-08-18T23:20:33.463457Z","steps":["trace[149300075] 'read index received' (duration: 999.64146ms)","trace[149300075] 'applied index is now lower than readState.Index' (duration: 173.052µs)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:33.463773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000101326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:33.463845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.147077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-18T23:20:33.463861Z","caller":"traceutil/trace.go:171","msg":"trace[1590777305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.000233457s","start":"2026-08-18T23:20:32.463602Z","end":"2026-08-18T23:20:33.463836Z","steps":["trace[1590777305] 'agreement among raft nodes before linearized reading' (duration: 1.000082035s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:33.46389Z","caller":"traceutil/trace.go:171","msg":"trace[496527357] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:794; }","duration":"281.260728ms","start":"2026-08-18T23:20:33.182614Z","end":"2026-08-18T23:20:33.463875Z","steps":["trace[496527357] 'agreement among raft nodes before linearized reading' (duration: 281.180247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:33.463937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:32.463587Z","time spent":"1.000319749s","remote":"127.0.0.1:49904","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-08-18T23:20:33.46403Z","caller":"traceutil/trace.go:171","msg":"trace[654758786] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.428240631s","start":"2026-08-18T23:20:32.035729Z","end":"2026-08-18T23:20:33.463969Z","steps":["trace[654758786] 'process raft request' (duration: 1.427584332s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:33.464287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:32.035718Z","time spent":"1.428421003s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:21:09.87695Z","caller":"traceutil/trace.go:171","msg":"trace[113808346] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"110.22597ms","start":"2026-08-18T23:21:09.766699Z","end":"2026-08-18T23:21:09.876925Z","steps":["trace[113808346] 'process raft request' (duration: 110.005207ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:14.292839Z","caller":"traceutil/trace.go:171","msg":"trace[1637401524] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"123.948029ms","start":"2026-08-18T23:21:14.168862Z","end":"2026-08-18T23:21:14.29281Z","steps":["trace[1637401524] 'process raft request' (duration: 123.790377ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:14.302134Z","caller":"traceutil/trace.go:171","msg":"trace[783631639] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"132.674744ms","start":"2026-08-18T23:21:14.169436Z","end":"2026-08-18T23:21:14.302111Z","steps":["trace[783631639] 'process raft request' (duration: 132.567222ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:32.40506Z","caller":"traceutil/trace.go:171","msg":"trace[1028020414] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"119.326532ms","start":"2026-08-18T23:21:32.285707Z","end":"2026-08-18T23:21:32.405034Z","steps":["trace[1028020414] 'process raft request' (duration: 109.500888ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:34.625403Z","caller":"traceutil/trace.go:171","msg":"trace[1206731919] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"130.361185ms","start":"2026-08-18T23:21:34.495003Z","end":"2026-08-18T23:21:34.625364Z","steps":["trace[1206731919] 'process raft request' (duration: 130.176033ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:22.74109Z","caller":"traceutil/trace.go:171","msg":"trace[998248667] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"123.977506ms","start":"2026-08-18T23:22:22.617066Z","end":"2026-08-18T23:22:22.741044Z","steps":["trace[998248667] 'process raft request' (duration: 123.752742ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:23.602827Z","caller":"traceutil/trace.go:171","msg":"trace[1493570147] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"135.553909ms","start":"2026-08-18T23:22:23.467249Z","end":"2026-08-18T23:22:23.602803Z","steps":["trace[1493570147] 'read index received' (duration: 135.3744ms)","trace[1493570147] 'applied index is now lower than readState.Index' (duration: 178.629µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:22:23.602883Z","caller":"traceutil/trace.go:171","msg":"trace[377584390] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"177.546942ms","start":"2026-08-18T23:22:23.425309Z","end":"2026-08-18T23:22:23.602856Z","steps":["trace[377584390] 'process raft request' (duration: 177.360262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:23.602958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.687436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:22:23.602995Z","caller":"traceutil/trace.go:171","msg":"trace[505045938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"135.76479ms","start":"2026-08-18T23:22:23.467217Z","end":"2026-08-18T23:22:23.602982Z","steps":["trace[505045938] 'agreement among raft nodes before linearized reading' (duration: 135.689286ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:29.167802Z","caller":"traceutil/trace.go:171","msg":"trace[1129416258] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"132.500589ms","start":"2026-08-18T23:22:29.035273Z","end":"2026-08-18T23:22:29.167773Z","steps":["trace[1129416258] 'process raft request' (duration: 119.601166ms)","trace[1129416258] 'compare' (duration: 12.710043ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:22:31.293247Z","caller":"traceutil/trace.go:171","msg":"trace[266923584] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"120.622501ms","start":"2026-08-18T23:22:31.172596Z","end":"2026-08-18T23:22:31.293218Z","steps":["trace[266923584] 'process raft request' (duration: 120.214781ms)"],"step_count":1}