{"level":"warn","ts":"2026-08-11T11:12:16.380496Z","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-11T11:12:16.380788Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-11T11:12:16.380912Z","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-11T11:12:16.38093Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-11T11:12:16.380965Z","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-11T11:12:16.38196Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-08-11T11:12:16.382087Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-11T11:12:16.387932Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.459276ms"} {"level":"info","ts":"2026-08-11T11:12:16.398157Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-08-11T11:12:16.398286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:12:16.398321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-08-11T11:12:16.398346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:12:16.398359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-08-11T11:12:16.398424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-08-11T11:12:16.403546Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:12:16.405264Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:12:16.406521Z","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-11T11:12:16.409139Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:12:16.409379Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:12:16.40964Z","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-11T11:12:16.409762Z","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-11T11:12:16.409779Z","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-11T11:12:16.413905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-08-11T11:12:16.414814Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-11T11:12:16.415471Z","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-11T11:12:16.415652Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-11T11:12:16.415729Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-11T11:12:16.415947Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:12:16.416025Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:12:16.899452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:12:16.899564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:12:16.8996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-08-11T11:12:16.899616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:12:16.899624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-11T11:12:16.899636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-08-11T11:12:16.899647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-11T11:12:16.900535Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:12:16.900761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:12:16.900935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:12:16.901237Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:16.901697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:12:16.901721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:12:16.907833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-08-11T11:12:16.908063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:16.908255Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:16.908291Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:16.910325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T11:12:25.450869Z","caller":"traceutil/trace.go:171","msg":"trace[1002092627] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"102.186725ms","start":"2026-08-11T11:12:25.348657Z","end":"2026-08-11T11:12:25.450844Z","steps":["trace[1002092627] 'process raft request' (duration: 102.034541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:26.607194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108913545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-08-11T11:12:26.607317Z","caller":"traceutil/trace.go:171","msg":"trace[740899985] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:333; }","duration":"1.109087779s","start":"2026-08-11T11:12:25.498201Z","end":"2026-08-11T11:12:26.607289Z","steps":["trace[740899985] 'range keys from in-memory index tree' (duration: 1.108783761s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:26.607369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:25.498175Z","time spent":"1.109179081s","remote":"127.0.0.1:47326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-08-11T11:12:26.607413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.355327ms","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-11T11:12:26.607488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059246923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:12:26.607559Z","caller":"traceutil/trace.go:171","msg":"trace[1371801745] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:333; }","duration":"1.059343246s","start":"2026-08-11T11:12:25.548202Z","end":"2026-08-11T11:12:26.607545Z","steps":["trace[1371801745] 'range keys from in-memory index tree' (duration: 1.059177122s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:26.60761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:25.548185Z","time spent":"1.059416137s","remote":"127.0.0.1:47326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-08-11T11:12:26.607513Z","caller":"traceutil/trace.go:171","msg":"trace[736460360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"292.512251ms","start":"2026-08-11T11:12:26.314979Z","end":"2026-08-11T11:12:26.607491Z","steps":["trace[736460360] 'range keys from in-memory index tree' (duration: 292.268175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:26.607413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.017077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-08-11T11:12:26.607879Z","caller":"traceutil/trace.go:171","msg":"trace[1498245538] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:333; }","duration":"428.51177ms","start":"2026-08-11T11:12:26.179325Z","end":"2026-08-11T11:12:26.607836Z","steps":["trace[1498245538] 'range keys from in-memory index tree' (duration: 427.894375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:26.607953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:26.179306Z","time spent":"428.628392ms","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-08-11T11:13:10.660375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.054909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:10.66051Z","caller":"traceutil/trace.go:171","msg":"trace[737029909] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"202.9268ms","start":"2026-08-11T11:13:10.457548Z","end":"2026-08-11T11:13:10.660474Z","steps":["trace[737029909] 'process raft request' (duration: 97.074781ms)","trace[737029909] 'compare' (duration: 104.860244ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:11.16627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.741286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:11.166398Z","caller":"traceutil/trace.go:171","msg":"trace[1895872305] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:505; }","duration":"115.892489ms","start":"2026-08-11T11:13:11.050488Z","end":"2026-08-11T11:13:11.166381Z","steps":["trace[1895872305] 'range keys from in-memory index tree' (duration: 115.719685ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:27.458688Z","caller":"traceutil/trace.go:171","msg":"trace[2100070430] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"315.033894ms","start":"2026-08-11T11:13:27.14363Z","end":"2026-08-11T11:13:27.458664Z","steps":["trace[2100070430] 'read index received' (duration: 308.915231ms)","trace[2100070430] 'applied index is now lower than readState.Index' (duration: 6.117843ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:27.45886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.205769ms","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-11T11:13:27.458891Z","caller":"traceutil/trace.go:171","msg":"trace[1404124725] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:605; }","duration":"315.287221ms","start":"2026-08-11T11:13:27.143595Z","end":"2026-08-11T11:13:27.458882Z","steps":["trace[1404124725] 'agreement among raft nodes before linearized reading' (duration: 315.182558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:27.458916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:27.143578Z","time spent":"315.332752ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-08-11T11:13:27.459109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.225706ms","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-11T11:13:27.459128Z","caller":"traceutil/trace.go:171","msg":"trace[979378155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"140.268248ms","start":"2026-08-11T11:13:27.318854Z","end":"2026-08-11T11:13:27.459122Z","steps":["trace[979378155] 'agreement among raft nodes before linearized reading' (duration: 140.226666ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:27.459367Z","caller":"traceutil/trace.go:171","msg":"trace[267498874] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"330.420198ms","start":"2026-08-11T11:13:27.128367Z","end":"2026-08-11T11:13:27.458787Z","steps":["trace[267498874] 'process raft request' (duration: 324.404788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:27.459553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:27.128342Z","time spent":"331.127126ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:27.711091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.283563ms","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-11T11:13:27.711207Z","caller":"traceutil/trace.go:171","msg":"trace[648363482] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:607; }","duration":"123.443258ms","start":"2026-08-11T11:13:27.587735Z","end":"2026-08-11T11:13:27.711178Z","steps":["trace[648363482] 'range keys from in-memory index tree' (duration: 123.15133ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.045314Z","caller":"traceutil/trace.go:171","msg":"trace[303804463] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"122.74881ms","start":"2026-08-11T11:13:27.922546Z","end":"2026-08-11T11:13:28.045294Z","steps":["trace[303804463] 'process raft request' (duration: 122.630237ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.105699Z","caller":"traceutil/trace.go:171","msg":"trace[1848734122] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"177.30957ms","start":"2026-08-11T11:13:27.928355Z","end":"2026-08-11T11:13:28.105665Z","steps":["trace[1848734122] 'process raft request' (duration: 176.984852ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.105723Z","caller":"traceutil/trace.go:171","msg":"trace[15150315] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"177.300421ms","start":"2026-08-11T11:13:27.928389Z","end":"2026-08-11T11:13:28.105689Z","steps":["trace[15150315] 'process raft request' (duration: 177.150947ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.105915Z","caller":"traceutil/trace.go:171","msg":"trace[788422677] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"170.138232ms","start":"2026-08-11T11:13:27.935759Z","end":"2026-08-11T11:13:28.105898Z","steps":["trace[788422677] 'process raft request' (duration: 169.837085ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.10592Z","caller":"traceutil/trace.go:171","msg":"trace[1412499887] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"170.093241ms","start":"2026-08-11T11:13:27.93581Z","end":"2026-08-11T11:13:28.105903Z","steps":["trace[1412499887] 'process raft request' (duration: 169.830255ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.105999Z","caller":"traceutil/trace.go:171","msg":"trace[1264888225] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"170.04004ms","start":"2026-08-11T11:13:27.935952Z","end":"2026-08-11T11:13:28.105992Z","steps":["trace[1264888225] 'process raft request' (duration: 169.861565ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.106055Z","caller":"traceutil/trace.go:171","msg":"trace[1470065734] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"165.22089ms","start":"2026-08-11T11:13:27.940821Z","end":"2026-08-11T11:13:28.106042Z","steps":["trace[1470065734] 'process raft request' (duration: 165.040585ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.106119Z","caller":"traceutil/trace.go:171","msg":"trace[1964425226] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"160.81124ms","start":"2026-08-11T11:13:27.945301Z","end":"2026-08-11T11:13:28.106112Z","steps":["trace[1964425226] 'process raft request' (duration: 160.613255ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.106211Z","caller":"traceutil/trace.go:171","msg":"trace[1786238981] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"152.887872ms","start":"2026-08-11T11:13:27.953309Z","end":"2026-08-11T11:13:28.106197Z","steps":["trace[1786238981] 'process raft request' (duration: 152.705507ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.10628Z","caller":"traceutil/trace.go:171","msg":"trace[1561636290] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"125.662834ms","start":"2026-08-11T11:13:27.980611Z","end":"2026-08-11T11:13:28.106273Z","steps":["trace[1561636290] 'process raft request' (duration: 125.490639ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.106287Z","caller":"traceutil/trace.go:171","msg":"trace[471233156] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"109.444999ms","start":"2026-08-11T11:13:27.996813Z","end":"2026-08-11T11:13:28.106257Z","steps":["trace[471233156] 'process raft request' (duration: 109.347797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.272343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.530061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:29.272643Z","caller":"traceutil/trace.go:171","msg":"trace[1880656547] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"136.207646ms","start":"2026-08-11T11:13:29.136402Z","end":"2026-08-11T11:13:29.272609Z","steps":["trace[1880656547] 'process raft request' (duration: 28.285955ms)","trace[1880656547] 'compare' (duration: 107.310796ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:29.272623Z","caller":"traceutil/trace.go:171","msg":"trace[201529352] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"127.081128ms","start":"2026-08-11T11:13:29.145461Z","end":"2026-08-11T11:13:29.272542Z","steps":["trace[201529352] 'read index received' (duration: 19.304751ms)","trace[201529352] 'applied index is now lower than readState.Index' (duration: 107.774667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:29.272783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.299894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:13:29.272843Z","caller":"traceutil/trace.go:171","msg":"trace[1558799507] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:675; }","duration":"127.383255ms","start":"2026-08-11T11:13:29.145435Z","end":"2026-08-11T11:13:29.272819Z","steps":["trace[1558799507] 'agreement among raft nodes before linearized reading' (duration: 127.276532ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:29.300928Z","caller":"traceutil/trace.go:171","msg":"trace[704131573] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"140.880163ms","start":"2026-08-11T11:13:29.160025Z","end":"2026-08-11T11:13:29.300905Z","steps":["trace[704131573] 'process raft request' (duration: 140.710909ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:29.369328Z","caller":"traceutil/trace.go:171","msg":"trace[2099671388] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"123.91532ms","start":"2026-08-11T11:13:29.245384Z","end":"2026-08-11T11:13:29.369299Z","steps":["trace[2099671388] 'process raft request' (duration: 122.665749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.904749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.90786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:29.904886Z","caller":"traceutil/trace.go:171","msg":"trace[870228472] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"197.817832ms","start":"2026-08-11T11:13:29.707049Z","end":"2026-08-11T11:13:29.904867Z","steps":["trace[870228472] 'read index received' (duration: 87.83654ms)","trace[870228472] 'applied index is now lower than readState.Index' (duration: 109.979702ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:29.904938Z","caller":"traceutil/trace.go:171","msg":"trace[1662392641] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"204.229102ms","start":"2026-08-11T11:13:29.700668Z","end":"2026-08-11T11:13:29.904897Z","steps":["trace[1662392641] 'process raft request' (duration: 94.079865ms)","trace[1662392641] 'compare' (duration: 109.737356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:29.905069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.995947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-08-11T11:13:29.905116Z","caller":"traceutil/trace.go:171","msg":"trace[944803048] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:685; }","duration":"198.085409ms","start":"2026-08-11T11:13:29.707015Z","end":"2026-08-11T11:13:29.905101Z","steps":["trace[944803048] 'agreement among raft nodes before linearized reading' (duration: 197.906194ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:29.910338Z","caller":"traceutil/trace.go:171","msg":"trace[219141465] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"149.98375ms","start":"2026-08-11T11:13:29.760307Z","end":"2026-08-11T11:13:29.910291Z","steps":["trace[219141465] 'process raft request' (duration: 149.791095ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:30.124662Z","caller":"traceutil/trace.go:171","msg":"trace[1773619149] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"147.898277ms","start":"2026-08-11T11:13:29.976742Z","end":"2026-08-11T11:13:30.124641Z","steps":["trace[1773619149] 'read index received' (duration: 64.97609ms)","trace[1773619149] 'applied index is now lower than readState.Index' (duration: 82.921427ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:30.124778Z","caller":"traceutil/trace.go:171","msg":"trace[1902457441] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"154.794009ms","start":"2026-08-11T11:13:29.969957Z","end":"2026-08-11T11:13:30.124751Z","steps":["trace[1902457441] 'process raft request' (duration: 71.82168ms)","trace[1902457441] 'compare' (duration: 82.63172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:30.12486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.102462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-08-11T11:13:30.124887Z","caller":"traceutil/trace.go:171","msg":"trace[980830969] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:687; }","duration":"148.177554ms","start":"2026-08-11T11:13:29.976701Z","end":"2026-08-11T11:13:30.124879Z","steps":["trace[980830969] 'agreement among raft nodes before linearized reading' (duration: 148.02309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:30.406634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.957911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-08-11T11:13:30.406731Z","caller":"traceutil/trace.go:171","msg":"trace[727978134] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:687; }","duration":"118.077774ms","start":"2026-08-11T11:13:30.288634Z","end":"2026-08-11T11:13:30.406712Z","steps":["trace[727978134] 'range keys from in-memory index tree' (duration: 117.690194ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:30.665923Z","caller":"traceutil/trace.go:171","msg":"trace[907548684] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"196.496419ms","start":"2026-08-11T11:13:30.469401Z","end":"2026-08-11T11:13:30.665897Z","steps":["trace[907548684] 'process raft request' (duration: 191.246908ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:33.487294Z","caller":"traceutil/trace.go:171","msg":"trace[927722429] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"193.3359ms","start":"2026-08-11T11:13:33.293936Z","end":"2026-08-11T11:13:33.487272Z","steps":["trace[927722429] 'process raft request' (duration: 193.054473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:33.636882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.715725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:33.637333Z","caller":"traceutil/trace.go:171","msg":"trace[2103793590] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"118.610587ms","start":"2026-08-11T11:13:33.518672Z","end":"2026-08-11T11:13:33.637282Z","steps":["trace[2103793590] 'read index received' (duration: 5.713022ms)","trace[2103793590] 'applied index is now lower than readState.Index' (duration: 112.817563ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:33.640319Z","caller":"traceutil/trace.go:171","msg":"trace[899529822] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"328.338836ms","start":"2026-08-11T11:13:33.311644Z","end":"2026-08-11T11:13:33.639983Z","steps":["trace[899529822] 'process raft request' (duration: 213.291428ms)","trace[899529822] 'compare' (duration: 110.052244ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:33.640596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:33.311597Z","time spent":"328.834658ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:33.641224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.530565ms","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-11T11:13:33.641322Z","caller":"traceutil/trace.go:171","msg":"trace[1372654739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"122.663868ms","start":"2026-08-11T11:13:33.518623Z","end":"2026-08-11T11:13:33.641287Z","steps":["trace[1372654739] 'agreement among raft nodes before linearized reading' (duration: 120.721769ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.018223Z","caller":"traceutil/trace.go:171","msg":"trace[431639145] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"186.767185ms","start":"2026-08-11T11:13:33.831436Z","end":"2026-08-11T11:13:34.018203Z","steps":["trace[431639145] 'read index received' (duration: 165.403804ms)","trace[431639145] 'applied index is now lower than readState.Index' (duration: 21.362681ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:34.018349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.897568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:13:34.018375Z","caller":"traceutil/trace.go:171","msg":"trace[399237762] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:705; }","duration":"186.986931ms","start":"2026-08-11T11:13:33.831377Z","end":"2026-08-11T11:13:34.018364Z","steps":["trace[399237762] 'agreement among raft nodes before linearized reading' (duration: 186.901089ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.018392Z","caller":"traceutil/trace.go:171","msg":"trace[635684826] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"257.237362ms","start":"2026-08-11T11:13:33.761125Z","end":"2026-08-11T11:13:34.018362Z","steps":["trace[635684826] 'process raft request' (duration: 235.799729ms)","trace[635684826] 'compare' (duration: 21.160286ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:34.747972Z","caller":"traceutil/trace.go:171","msg":"trace[331835830] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"357.932445ms","start":"2026-08-11T11:13:34.390011Z","end":"2026-08-11T11:13:34.747944Z","steps":["trace[331835830] 'read index received' (duration: 357.075033ms)","trace[331835830] 'applied index is now lower than readState.Index' (duration: 856.342µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:34.74875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.633812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:13:34.748765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.819968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:34.748853Z","caller":"traceutil/trace.go:171","msg":"trace[2020436539] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:706; }","duration":"358.853737ms","start":"2026-08-11T11:13:34.389968Z","end":"2026-08-11T11:13:34.748821Z","steps":["trace[2020436539] 'agreement among raft nodes before linearized reading' (duration: 358.600181ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.748888Z","caller":"traceutil/trace.go:171","msg":"trace[1916628617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"111.989792ms","start":"2026-08-11T11:13:34.636865Z","end":"2026-08-11T11:13:34.748855Z","steps":["trace[1916628617] 'agreement among raft nodes before linearized reading' (duration: 111.754016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:34.748951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:34.389948Z","time spent":"358.98604ms","remote":"127.0.0.1:47340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:13:34.749028Z","caller":"traceutil/trace.go:171","msg":"trace[1910724501] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"574.961505ms","start":"2026-08-11T11:13:34.174042Z","end":"2026-08-11T11:13:34.749004Z","steps":["trace[1910724501] 'process raft request' (duration: 573.351335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:34.749171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:34.17402Z","time spent":"575.065968ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:13:35.078652Z","caller":"traceutil/trace.go:171","msg":"trace[122237292] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"270.507935ms","start":"2026-08-11T11:13:34.808121Z","end":"2026-08-11T11:13:35.078629Z","steps":["trace[122237292] 'process raft request' (duration: 270.347691ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:36.873745Z","caller":"traceutil/trace.go:171","msg":"trace[1843555411] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"217.404361ms","start":"2026-08-11T11:13:36.656321Z","end":"2026-08-11T11:13:36.873725Z","steps":["trace[1843555411] 'process raft request' (duration: 216.927339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:51.066607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785802102,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:13:51.332237Z","caller":"traceutil/trace.go:171","msg":"trace[133519173] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"765.971898ms","start":"2026-08-11T11:13:50.56624Z","end":"2026-08-11T11:13:51.332212Z","steps":["trace[133519173] 'read index received' (duration: 723.095589ms)","trace[133519173] 'applied index is now lower than readState.Index' (duration: 42.875379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:51.332416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.177032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:51.332458Z","caller":"traceutil/trace.go:171","msg":"trace[1105528996] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:876; }","duration":"346.279564ms","start":"2026-08-11T11:13:50.986171Z","end":"2026-08-11T11:13:51.332451Z","steps":["trace[1105528996] 'agreement among raft nodes before linearized reading' (duration: 346.186782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:51.332488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:50.986147Z","time spent":"346.334055ms","remote":"127.0.0.1:47520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:13:51.332503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.900582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:13:51.33257Z","caller":"traceutil/trace.go:171","msg":"trace[341570820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:876; }","duration":"151.016965ms","start":"2026-08-11T11:13:51.181539Z","end":"2026-08-11T11:13:51.332556Z","steps":["trace[341570820] 'agreement among raft nodes before linearized reading' (duration: 150.844631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:51.332578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.310396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xlrgf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-11T11:13:51.332631Z","caller":"traceutil/trace.go:171","msg":"trace[534173294] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"820.636181ms","start":"2026-08-11T11:13:50.511637Z","end":"2026-08-11T11:13:51.332274Z","steps":["trace[534173294] 'process raft request' (duration: 777.819483ms)","trace[534173294] 'compare' (duration: 42.50924ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:51.332923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:50.511613Z","time spent":"821.162993ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:51.333009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.725369ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:51.333292Z","caller":"traceutil/trace.go:171","msg":"trace[771043996] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:875; }","duration":"282.934834ms","start":"2026-08-11T11:13:51.050271Z","end":"2026-08-11T11:13:51.333206Z","steps":["trace[771043996] 'range keys from in-memory index tree' (duration: 281.791246ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:51.33267Z","caller":"traceutil/trace.go:171","msg":"trace[1029498400] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xlrgf; range_end:; response_count:1; response_revision:876; }","duration":"766.45511ms","start":"2026-08-11T11:13:50.566196Z","end":"2026-08-11T11:13:51.332651Z","steps":["trace[1029498400] 'agreement among raft nodes before linearized reading' (duration: 766.151803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:51.333362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:50.566174Z","time spent":"767.168987ms","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xlrgf\" "} {"level":"info","ts":"2026-08-11T11:13:53.70589Z","caller":"traceutil/trace.go:171","msg":"trace[760670277] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"118.336031ms","start":"2026-08-11T11:13:53.587513Z","end":"2026-08-11T11:13:53.705855Z","steps":["trace[760670277] 'process raft request' (duration: 118.158996ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:53.831939Z","caller":"traceutil/trace.go:171","msg":"trace[101744809] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"121.247203ms","start":"2026-08-11T11:13:53.710669Z","end":"2026-08-11T11:13:53.831916Z","steps":["trace[101744809] 'process raft request' (duration: 79.739038ms)","trace[101744809] 'compare' (duration: 41.415983ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:20.991281Z","caller":"traceutil/trace.go:171","msg":"trace[105209264] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"354.56531ms","start":"2026-08-11T11:14:20.636696Z","end":"2026-08-11T11:14:20.991261Z","steps":["trace[105209264] 'read index received' (duration: 354.466208ms)","trace[105209264] 'applied index is now lower than readState.Index' (duration: 97.572µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:20.991336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:20.447803Z","time spent":"543.525591ms","remote":"127.0.0.1:47144","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T11:14:20.991398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.687473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:14:20.991441Z","caller":"traceutil/trace.go:171","msg":"trace[1645641911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"354.747934ms","start":"2026-08-11T11:14:20.636682Z","end":"2026-08-11T11:14:20.99143Z","steps":["trace[1645641911] 'agreement among raft nodes before linearized reading' (duration: 354.663582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:20.991478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:20.636628Z","time spent":"354.840807ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T11:14:21.012788Z","caller":"traceutil/trace.go:171","msg":"trace[892606815] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"107.683165ms","start":"2026-08-11T11:14:20.905085Z","end":"2026-08-11T11:14:21.012768Z","steps":["trace[892606815] 'process raft request' (duration: 101.674205ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:22.062699Z","caller":"traceutil/trace.go:171","msg":"trace[1537427316] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"113.192982ms","start":"2026-08-11T11:14:21.949489Z","end":"2026-08-11T11:14:22.062682Z","steps":["trace[1537427316] 'read index received' (duration: 113.071239ms)","trace[1537427316] 'applied index is now lower than readState.Index' (duration: 121.163µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:22.062857Z","caller":"traceutil/trace.go:171","msg":"trace[277667401] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"148.916823ms","start":"2026-08-11T11:14:21.913897Z","end":"2026-08-11T11:14:22.062814Z","steps":["trace[277667401] 'process raft request' (duration: 148.672477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:22.062923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.425518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T11:14:22.062981Z","caller":"traceutil/trace.go:171","msg":"trace[398230359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"113.51728ms","start":"2026-08-11T11:14:21.949444Z","end":"2026-08-11T11:14:22.062961Z","steps":["trace[398230359] 'agreement among raft nodes before linearized reading' (duration: 113.355816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:22.216703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.398282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:22.216926Z","caller":"traceutil/trace.go:171","msg":"trace[269136486] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"150.37101ms","start":"2026-08-11T11:14:22.066534Z","end":"2026-08-11T11:14:22.216905Z","steps":["trace[269136486] 'process raft request' (duration: 31.6803ms)","trace[269136486] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 117.999082ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:22.217027Z","caller":"traceutil/trace.go:171","msg":"trace[1029136977] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"150.049001ms","start":"2026-08-11T11:14:22.066969Z","end":"2026-08-11T11:14:22.217018Z","steps":["trace[1029136977] 'process raft request' (duration: 149.814935ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:22.409873Z","caller":"traceutil/trace.go:171","msg":"trace[270254181] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"188.52478ms","start":"2026-08-11T11:14:22.221321Z","end":"2026-08-11T11:14:22.409846Z","steps":["trace[270254181] 'process raft request' (duration: 157.067526ms)","trace[270254181] 'compare' (duration: 31.349891ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:26.394923Z","caller":"traceutil/trace.go:171","msg":"trace[1292698586] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"148.340649ms","start":"2026-08-11T11:14:26.24653Z","end":"2026-08-11T11:14:26.394871Z","steps":["trace[1292698586] 'process raft request' (duration: 148.205855ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:26.432345Z","caller":"traceutil/trace.go:171","msg":"trace[1740677478] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1137; }","duration":"102.348132ms","start":"2026-08-11T11:14:26.329973Z","end":"2026-08-11T11:14:26.432321Z","steps":["trace[1740677478] 'read index received' (duration: 64.779356ms)","trace[1740677478] 'applied index is now lower than readState.Index' (duration: 37.568046ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:26.432378Z","caller":"traceutil/trace.go:171","msg":"trace[232792327] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"182.662664ms","start":"2026-08-11T11:14:26.249696Z","end":"2026-08-11T11:14:26.432359Z","steps":["trace[232792327] 'process raft request' (duration: 182.51418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:26.432528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.541747ms","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-11T11:14:26.432566Z","caller":"traceutil/trace.go:171","msg":"trace[1447458788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"102.643109ms","start":"2026-08-11T11:14:26.329911Z","end":"2026-08-11T11:14:26.432554Z","steps":["trace[1447458788] 'agreement among raft nodes before linearized reading' (duration: 102.495085ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:27.267593Z","caller":"traceutil/trace.go:171","msg":"trace[1764315779] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"216.627101ms","start":"2026-08-11T11:14:27.050933Z","end":"2026-08-11T11:14:27.26756Z","steps":["trace[1764315779] 'process raft request' (duration: 216.474397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:27.799697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.967926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:14:27.799765Z","caller":"traceutil/trace.go:171","msg":"trace[728083184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"265.080468ms","start":"2026-08-11T11:14:27.534669Z","end":"2026-08-11T11:14:27.799749Z","steps":["trace[728083184] 'range keys from in-memory index tree' (duration: 264.806972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:27.799781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.49433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:14:27.799902Z","caller":"traceutil/trace.go:171","msg":"trace[1458588409] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1098; }","duration":"241.666665ms","start":"2026-08-11T11:14:27.558209Z","end":"2026-08-11T11:14:27.799876Z","steps":["trace[1458588409] 'count revisions from in-memory index tree' (duration: 241.410508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:27.799886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.730071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:14:27.799983Z","caller":"traceutil/trace.go:171","msg":"trace[2104731927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"161.878335ms","start":"2026-08-11T11:14:27.638084Z","end":"2026-08-11T11:14:27.799962Z","steps":["trace[2104731927] 'range keys from in-memory index tree' (duration: 161.65357ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:28.534367Z","caller":"traceutil/trace.go:171","msg":"trace[890397088] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"131.257223ms","start":"2026-08-11T11:14:28.403087Z","end":"2026-08-11T11:14:28.534344Z","steps":["trace[890397088] 'process raft request' (duration: 131.085859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:29.458201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785802637,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:14:29.541558Z","caller":"traceutil/trace.go:171","msg":"trace[126292869] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.003392587s","start":"2026-08-11T11:14:28.538144Z","end":"2026-08-11T11:14:29.541537Z","steps":["trace[126292869] 'process raft request' (duration: 1.003217032s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:29.541695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:28.538127Z","time spent":"1.00350098s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:30.361288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.744436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:30.361563Z","caller":"traceutil/trace.go:171","msg":"trace[1804218671] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1144; }","duration":"1.403601485s","start":"2026-08-11T11:14:28.95794Z","end":"2026-08-11T11:14:30.361541Z","steps":["trace[1804218671] 'read index received' (duration: 583.473258ms)","trace[1804218671] 'applied index is now lower than readState.Index' (duration: 820.126997ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:30.361681Z","caller":"traceutil/trace.go:171","msg":"trace[869603303] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"1.822863308s","start":"2026-08-11T11:14:28.538799Z","end":"2026-08-11T11:14:30.361662Z","steps":["trace[869603303] 'process raft request' (duration: 1.822612522s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:30.361701Z","caller":"traceutil/trace.go:171","msg":"trace[1382216507] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"1.087926825s","start":"2026-08-11T11:14:29.273759Z","end":"2026-08-11T11:14:30.361685Z","steps":["trace[1382216507] 'process raft request' (duration: 1.08772506s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:30.36173Z","caller":"traceutil/trace.go:171","msg":"trace[1203965031] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.822969791s","start":"2026-08-11T11:14:28.538706Z","end":"2026-08-11T11:14:30.361675Z","steps":["trace[1203965031] 'process raft request' (duration: 1.126738392s)","trace[1203965031] 'compare' (duration: 695.562122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:30.361777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:28.538774Z","time spent":"1.822951341s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:30.361793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:29.273742Z","time spent":"1.088000016s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:30.361863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:28.538699Z","time spent":"1.823093155s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:30.361972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404030636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:14:30.362001Z","caller":"traceutil/trace.go:171","msg":"trace[168393298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"1.404056026s","start":"2026-08-11T11:14:28.957935Z","end":"2026-08-11T11:14:30.361991Z","steps":["trace[168393298] 'agreement among raft nodes before linearized reading' (duration: 1.404013175s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:30.362018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:28.957898Z","time spent":"1.404116648s","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:14:30.361987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.065008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:14:30.362056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.553422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:14:30.362062Z","caller":"traceutil/trace.go:171","msg":"trace[369920799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"725.192301ms","start":"2026-08-11T11:14:29.636851Z","end":"2026-08-11T11:14:30.362043Z","steps":["trace[369920799] 'agreement among raft nodes before linearized reading' (duration: 725.054867ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:30.362101Z","caller":"traceutil/trace.go:171","msg":"trace[571279227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"545.630354ms","start":"2026-08-11T11:14:29.816456Z","end":"2026-08-11T11:14:30.362086Z","steps":["trace[571279227] 'agreement among raft nodes before linearized reading' (duration: 545.502781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:30.362107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:29.636832Z","time spent":"725.266703ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:14:30.362133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:29.816436Z","time spent":"545.689526ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:14:40.904912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785802721,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:14:41.276115Z","caller":"traceutil/trace.go:171","msg":"trace[1993983538] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"871.887588ms","start":"2026-08-11T11:14:40.404205Z","end":"2026-08-11T11:14:41.276092Z","steps":["trace[1993983538] 'read index received' (duration: 871.714484ms)","trace[1993983538] 'applied index is now lower than readState.Index' (duration: 172.174µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:41.276294Z","caller":"traceutil/trace.go:171","msg":"trace[133865949] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"881.898688ms","start":"2026-08-11T11:14:40.394373Z","end":"2026-08-11T11:14:41.276272Z","steps":["trace[133865949] 'process raft request' (duration: 881.5746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.646922ms","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-11T11:14:41.276563Z","caller":"traceutil/trace.go:171","msg":"trace[1368692994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"867.777356ms","start":"2026-08-11T11:14:40.408773Z","end":"2026-08-11T11:14:41.27655Z","steps":["trace[1368692994] 'agreement among raft nodes before linearized reading' (duration: 867.53528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.408756Z","time spent":"867.859727ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:14:41.276622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.394347Z","time spent":"882.081123ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:41.276645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.010684ms","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-11T11:14:41.276719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.265771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T11:14:41.276712Z","caller":"traceutil/trace.go:171","msg":"trace[2049520874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1135; }","duration":"692.107686ms","start":"2026-08-11T11:14:40.584588Z","end":"2026-08-11T11:14:41.276696Z","steps":["trace[2049520874] 'agreement among raft nodes before linearized reading' (duration: 691.950152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.845707ms","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-11T11:14:41.276475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.538805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:14:41.276768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.584568Z","time spent":"692.187698ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T11:14:41.276789Z","caller":"traceutil/trace.go:171","msg":"trace[42469914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1135; }","duration":"867.984461ms","start":"2026-08-11T11:14:40.408785Z","end":"2026-08-11T11:14:41.27677Z","steps":["trace[42469914] 'agreement among raft nodes before linearized reading' (duration: 867.694334ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:41.276798Z","caller":"traceutil/trace.go:171","msg":"trace[504546762] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:1135; }","duration":"828.338643ms","start":"2026-08-11T11:14:40.448408Z","end":"2026-08-11T11:14:41.276747Z","steps":["trace[504546762] 'agreement among raft nodes before linearized reading' (duration: 828.043625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.40877Z","time spent":"868.070473ms","remote":"127.0.0.1:47372","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-11T11:14:41.276824Z","caller":"traceutil/trace.go:171","msg":"trace[2026520558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"639.908935ms","start":"2026-08-11T11:14:40.636888Z","end":"2026-08-11T11:14:41.276797Z","steps":["trace[2026520558] 'agreement among raft nodes before linearized reading' (duration: 639.530255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.636872Z","time spent":"640.023617ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:14:41.27668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.390201ms","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-11T11:14:41.277004Z","caller":"traceutil/trace.go:171","msg":"trace[713507923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"872.814492ms","start":"2026-08-11T11:14:40.404174Z","end":"2026-08-11T11:14:41.276988Z","steps":["trace[713507923] 'agreement among raft nodes before linearized reading' (duration: 872.35463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.276873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.448387Z","time spent":"828.476026ms","remote":"127.0.0.1:47144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-08-11T11:14:41.277051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.404141Z","time spent":"872.898134ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:14:41.277475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.608896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:14:41.277538Z","caller":"traceutil/trace.go:171","msg":"trace[209026274] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1135; }","duration":"354.236892ms","start":"2026-08-11T11:14:40.923284Z","end":"2026-08-11T11:14:41.277521Z","steps":["trace[209026274] 'agreement among raft nodes before linearized reading' (duration: 353.226467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.277595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:40.923262Z","time spent":"354.315574ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:14:41.762416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.451614ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-11T11:14:41.762579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.278231Z","time spent":"484.342905ms","remote":"127.0.0.1:47144","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T11:14:41.762696Z","caller":"traceutil/trace.go:171","msg":"trace[1610220832] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"481.160977ms","start":"2026-08-11T11:14:41.281521Z","end":"2026-08-11T11:14:41.762682Z","steps":["trace[1610220832] 'process raft request' (duration: 481.090605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.762779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.281501Z","time spent":"481.221968ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:41.762865Z","caller":"traceutil/trace.go:171","msg":"trace[207911020] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"481.370362ms","start":"2026-08-11T11:14:41.281488Z","end":"2026-08-11T11:14:41.762859Z","steps":["trace[207911020] 'process raft request' (duration: 481.017973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.762897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.28147Z","time spent":"481.407773ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:41.763015Z","caller":"traceutil/trace.go:171","msg":"trace[1949982148] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1181; }","duration":"171.997078ms","start":"2026-08-11T11:14:41.591002Z","end":"2026-08-11T11:14:41.762999Z","steps":["trace[1949982148] 'read index received' (duration: 116.907134ms)","trace[1949982148] 'applied index is now lower than readState.Index' (duration: 55.088994ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:41.763155Z","caller":"traceutil/trace.go:171","msg":"trace[827402294] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"481.485024ms","start":"2026-08-11T11:14:41.281642Z","end":"2026-08-11T11:14:41.763127Z","steps":["trace[827402294] 'process raft request' (duration: 481.014422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.763175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.145313ms","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-11T11:14:41.763197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.281632Z","time spent":"481.539916ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:41.763209Z","caller":"traceutil/trace.go:171","msg":"trace[629622533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1139; }","duration":"172.245895ms","start":"2026-08-11T11:14:41.590953Z","end":"2026-08-11T11:14:41.763199Z","steps":["trace[629622533] 'agreement among raft nodes before linearized reading' (duration: 172.092531ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:41.763283Z","caller":"traceutil/trace.go:171","msg":"trace[1987440955] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"481.027073ms","start":"2026-08-11T11:14:41.28223Z","end":"2026-08-11T11:14:41.763257Z","steps":["trace[1987440955] 'process raft request' (duration: 480.678005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:41.763489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.28222Z","time spent":"481.121826ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:42.444867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.946917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:42.445159Z","caller":"traceutil/trace.go:171","msg":"trace[1953311644] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"678.111576ms","start":"2026-08-11T11:14:41.767034Z","end":"2026-08-11T11:14:42.445146Z","steps":["trace[1953311644] 'process raft request' (duration: 677.906491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:42.445217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.767025Z","time spent":"678.167828ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:42.445444Z","caller":"traceutil/trace.go:171","msg":"trace[1662150963] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"680.839574ms","start":"2026-08-11T11:14:41.764574Z","end":"2026-08-11T11:14:42.445414Z","steps":["trace[1662150963] 'process raft request' (duration: 143.270622ms)","trace[1662150963] 'compare' (duration: 536.763532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:42.445595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:41.764543Z","time spent":"680.979887ms","remote":"127.0.0.1:47144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:42.83881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.014569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:42.83891Z","caller":"traceutil/trace.go:171","msg":"trace[590826076] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"391.04709ms","start":"2026-08-11T11:14:42.447846Z","end":"2026-08-11T11:14:42.838893Z","steps":["trace[590826076] 'read index received' (duration: 147.882177ms)","trace[590826076] 'applied index is now lower than readState.Index' (duration: 243.163813ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:42.838993Z","caller":"traceutil/trace.go:171","msg":"trace[216190640] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"623.160677ms","start":"2026-08-11T11:14:42.215821Z","end":"2026-08-11T11:14:42.838982Z","steps":["trace[216190640] 'process raft request' (duration: 379.890752ms)","trace[216190640] 'compare' (duration: 242.631159ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:42.839089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.958476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:14:42.839109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:42.215792Z","time spent":"623.28444ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:42.83917Z","caller":"traceutil/trace.go:171","msg":"trace[1406011514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"202.087439ms","start":"2026-08-11T11:14:42.637063Z","end":"2026-08-11T11:14:42.839151Z","steps":["trace[1406011514] 'agreement among raft nodes before linearized reading' (duration: 201.954096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:42.839494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.624864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-11T11:14:42.839585Z","caller":"traceutil/trace.go:171","msg":"trace[1009889654] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1142; }","duration":"391.779058ms","start":"2026-08-11T11:14:42.447786Z","end":"2026-08-11T11:14:42.839565Z","steps":["trace[1009889654] 'agreement among raft nodes before linearized reading' (duration: 391.569363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:42.839627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:42.447772Z","time spent":"391.84745ms","remote":"127.0.0.1:47290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-11T11:14:43.837377Z","caller":"traceutil/trace.go:171","msg":"trace[1044247007] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"552.534706ms","start":"2026-08-11T11:14:43.284797Z","end":"2026-08-11T11:14:43.837332Z","steps":["trace[1044247007] 'process raft request' (duration: 552.360352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:43.837621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:43.284766Z","time spent":"552.712471ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:44.168936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.11779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:44.169043Z","caller":"traceutil/trace.go:171","msg":"trace[145632669] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"328.234174ms","start":"2026-08-11T11:14:43.840786Z","end":"2026-08-11T11:14:44.16902Z","steps":["trace[145632669] 'process raft request' (duration: 212.94774ms)","trace[145632669] 'compare' (duration: 114.844633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:44.169098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:43.840758Z","time spent":"328.315316ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:44.9257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.424671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:44.926049Z","caller":"traceutil/trace.go:171","msg":"trace[1658941356] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1191; }","duration":"474.157162ms","start":"2026-08-11T11:14:44.451872Z","end":"2026-08-11T11:14:44.926029Z","steps":["trace[1658941356] 'read index received' (duration: 181.28534ms)","trace[1658941356] 'applied index is now lower than readState.Index' (duration: 292.870752ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:44.926154Z","caller":"traceutil/trace.go:171","msg":"trace[115791763] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"1.084260493s","start":"2026-08-11T11:14:43.841857Z","end":"2026-08-11T11:14:44.926117Z","steps":["trace[115791763] 'process raft request' (duration: 791.34836ms)","trace[115791763] 'compare' (duration: 292.144474ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:44.92627Z","caller":"traceutil/trace.go:171","msg":"trace[1567227577] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"1.084022757s","start":"2026-08-11T11:14:43.842133Z","end":"2026-08-11T11:14:44.926156Z","steps":["trace[1567227577] 'process raft request' (duration: 1.083766341s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:44.926345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:43.841831Z","time spent":"1.084419187s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:44.926361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:43.842107Z","time spent":"1.084205382s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:44.926409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.534451ms","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-11T11:14:44.926457Z","caller":"traceutil/trace.go:171","msg":"trace[1486034428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1147; }","duration":"474.627993ms","start":"2026-08-11T11:14:44.451816Z","end":"2026-08-11T11:14:44.926444Z","steps":["trace[1486034428] 'agreement among raft nodes before linearized reading' (duration: 474.425838ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:44.926449Z","caller":"traceutil/trace.go:171","msg":"trace[462371894] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"1.084182531s","start":"2026-08-11T11:14:43.842249Z","end":"2026-08-11T11:14:44.926432Z","steps":["trace[462371894] 'process raft request' (duration: 1.08372527s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:44.926495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:44.451789Z","time spent":"474.697285ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T11:14:44.926525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.589831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:14:44.926573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:43.842245Z","time spent":"1.084283033s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:44.926595Z","caller":"traceutil/trace.go:171","msg":"trace[495869290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1147; }","duration":"285.706914ms","start":"2026-08-11T11:14:44.64087Z","end":"2026-08-11T11:14:44.926577Z","steps":["trace[495869290] 'agreement among raft nodes before linearized reading' (duration: 285.579ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:47.280194Z","caller":"traceutil/trace.go:171","msg":"trace[1718858762] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"344.500929ms","start":"2026-08-11T11:14:46.935656Z","end":"2026-08-11T11:14:47.280157Z","steps":["trace[1718858762] 'process raft request' (duration: 344.105689ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:47.280384Z","caller":"traceutil/trace.go:171","msg":"trace[1670741941] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1199; }","duration":"251.787488ms","start":"2026-08-11T11:14:47.028563Z","end":"2026-08-11T11:14:47.28035Z","steps":["trace[1670741941] 'read index received' (duration: 619.716µs)","trace[1670741941] 'applied index is now lower than readState.Index' (duration: 251.165182ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:47.280461Z","caller":"traceutil/trace.go:171","msg":"trace[1052597409] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"343.507655ms","start":"2026-08-11T11:14:46.936915Z","end":"2026-08-11T11:14:47.280422Z","steps":["trace[1052597409] 'process raft request' (duration: 343.112775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:14:47.280493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:46.935626Z","time spent":"344.732895ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:14:47.280617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.027024ms","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-11T11:14:47.28065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:14:46.936906Z","time spent":"343.623658ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:14:47.280661Z","caller":"traceutil/trace.go:171","msg":"trace[1043940242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"252.121187ms","start":"2026-08-11T11:14:47.028526Z","end":"2026-08-11T11:14:47.280647Z","steps":["trace[1043940242] 'agreement among raft nodes before linearized reading' (duration: 251.932822ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:47.491594Z","caller":"traceutil/trace.go:171","msg":"trace[833882211] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"207.110244ms","start":"2026-08-11T11:14:47.284437Z","end":"2026-08-11T11:14:47.491547Z","steps":["trace[833882211] 'process raft request' (duration: 191.077334ms)","trace[833882211] 'compare' (duration: 15.866176ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:48.028055Z","caller":"traceutil/trace.go:171","msg":"trace[1807306133] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"124.36641ms","start":"2026-08-11T11:14:47.903664Z","end":"2026-08-11T11:14:48.02803Z","steps":["trace[1807306133] 'process raft request' (duration: 123.95824ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:04.432388Z","caller":"traceutil/trace.go:171","msg":"trace[283561262] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"171.350072ms","start":"2026-08-11T11:15:04.261014Z","end":"2026-08-11T11:15:04.432364Z","steps":["trace[283561262] 'read index received' (duration: 171.039164ms)","trace[283561262] 'applied index is now lower than readState.Index' (duration: 310.028µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:04.432613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.580687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:15:04.432654Z","caller":"traceutil/trace.go:171","msg":"trace[617839747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"171.66803ms","start":"2026-08-11T11:15:04.260974Z","end":"2026-08-11T11:15:04.432642Z","steps":["trace[617839747] 'agreement among raft nodes before linearized reading' (duration: 171.498965ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:04.432964Z","caller":"traceutil/trace.go:171","msg":"trace[251271952] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"350.5911ms","start":"2026-08-11T11:15:04.082326Z","end":"2026-08-11T11:15:04.432918Z","steps":["trace[251271952] 'process raft request' (duration: 349.793321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:04.433246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:04.082305Z","time spent":"350.840987ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:15:06.076914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:06.577715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:06.594771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20042963s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:15:06.595479Z","caller":"traceutil/trace.go:171","msg":"trace[1521203143] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.200930842s","start":"2026-08-11T11:15:05.394265Z","end":"2026-08-11T11:15:06.595196Z","steps":["trace[1521203143] 'process raft request' (duration: 1.200773118s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:06.59559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:05.394249Z","time spent":"1.20127569s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:06.596527Z","caller":"traceutil/trace.go:171","msg":"trace[1030512572] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.193584449s","start":"2026-08-11T11:15:05.402928Z","end":"2026-08-11T11:15:06.596512Z","steps":["trace[1030512572] 'process raft request' (duration: 1.193351093s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:06.596598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:05.402908Z","time spent":"1.19364932s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:06.59964Z","caller":"traceutil/trace.go:171","msg":"trace[1409321346] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"749.681311ms","start":"2026-08-11T11:15:05.849942Z","end":"2026-08-11T11:15:06.599623Z","steps":["trace[1409321346] 'process raft request' (duration: 749.379763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:06.599735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:05.849917Z","time spent":"749.772244ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:06.59974Z","caller":"traceutil/trace.go:171","msg":"trace[851567507] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1316; }","duration":"1.02354004s","start":"2026-08-11T11:15:05.576186Z","end":"2026-08-11T11:15:06.599726Z","steps":["trace[851567507] 'read index received' (duration: 1.018819412s)","trace[851567507] 'applied index is now lower than readState.Index' (duration: 4.719668ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:06.599916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023738004s","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-11T11:15:06.59993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.803745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:06.599947Z","caller":"traceutil/trace.go:171","msg":"trace[793458570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"1.023815327s","start":"2026-08-11T11:15:05.576124Z","end":"2026-08-11T11:15:06.599939Z","steps":["trace[793458570] 'agreement among raft nodes before linearized reading' (duration: 1.023722505s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:06.599953Z","caller":"traceutil/trace.go:171","msg":"trace[1594705145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"962.866597ms","start":"2026-08-11T11:15:05.637079Z","end":"2026-08-11T11:15:06.599946Z","steps":["trace[1594705145] 'agreement among raft nodes before linearized reading' (duration: 962.829446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:06.599967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:05.576105Z","time spent":"1.023857667s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T11:15:06.599973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:05.637059Z","time spent":"962.909358ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T11:15:06.600063Z","caller":"traceutil/trace.go:171","msg":"trace[713002211] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"161.548268ms","start":"2026-08-11T11:15:06.438507Z","end":"2026-08-11T11:15:06.600055Z","steps":["trace[713002211] 'process raft request' (duration: 161.083116ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:08.849411Z","caller":"traceutil/trace.go:171","msg":"trace[709475778] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"243.925131ms","start":"2026-08-11T11:15:08.605466Z","end":"2026-08-11T11:15:08.849391Z","steps":["trace[709475778] 'process raft request' (duration: 241.419009ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:08.849512Z","caller":"traceutil/trace.go:171","msg":"trace[1532394991] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1324; }","duration":"234.787744ms","start":"2026-08-11T11:15:08.614692Z","end":"2026-08-11T11:15:08.84948Z","steps":["trace[1532394991] 'read index received' (duration: 85.983834ms)","trace[1532394991] 'applied index is now lower than readState.Index' (duration: 148.80068ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:08.84957Z","caller":"traceutil/trace.go:171","msg":"trace[1056503030] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"243.680305ms","start":"2026-08-11T11:15:08.60588Z","end":"2026-08-11T11:15:08.849561Z","steps":["trace[1056503030] 'process raft request' (duration: 243.46246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:08.849735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.57381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:08.849769Z","caller":"traceutil/trace.go:171","msg":"trace[1594939816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"212.646191ms","start":"2026-08-11T11:15:08.637114Z","end":"2026-08-11T11:15:08.849761Z","steps":["trace[1594939816] 'agreement among raft nodes before linearized reading' (duration: 212.59134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:08.849792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.089741ms","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-11T11:15:08.849816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.662751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:15:08.849872Z","caller":"traceutil/trace.go:171","msg":"trace[1459830352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"234.780414ms","start":"2026-08-11T11:15:08.615077Z","end":"2026-08-11T11:15:08.849857Z","steps":["trace[1459830352] 'agreement among raft nodes before linearized reading' (duration: 234.583559ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:08.849866Z","caller":"traceutil/trace.go:171","msg":"trace[1479271656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"235.205374ms","start":"2026-08-11T11:15:08.614643Z","end":"2026-08-11T11:15:08.849849Z","steps":["trace[1479271656] 'agreement among raft nodes before linearized reading' (duration: 234.965648ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:21.086128Z","caller":"traceutil/trace.go:171","msg":"trace[870555478] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"143.007565ms","start":"2026-08-11T11:15:20.943096Z","end":"2026-08-11T11:15:21.086103Z","steps":["trace[870555478] 'process raft request' (duration: 142.79554ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:23.744084Z","caller":"traceutil/trace.go:171","msg":"trace[672282789] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"107.331076ms","start":"2026-08-11T11:15:23.636723Z","end":"2026-08-11T11:15:23.744054Z","steps":["trace[672282789] 'read index received' (duration: 106.770622ms)","trace[672282789] 'applied index is now lower than readState.Index' (duration: 558.124µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:23.744221Z","caller":"traceutil/trace.go:171","msg":"trace[1747309187] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"143.857217ms","start":"2026-08-11T11:15:23.600321Z","end":"2026-08-11T11:15:23.744178Z","steps":["trace[1747309187] 'process raft request' (duration: 143.157129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:23.744267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.526601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:23.744313Z","caller":"traceutil/trace.go:171","msg":"trace[330780561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"107.622224ms","start":"2026-08-11T11:15:23.63668Z","end":"2026-08-11T11:15:23.744302Z","steps":["trace[330780561] 'agreement among raft nodes before linearized reading' (duration: 107.506291ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:25.068355Z","caller":"traceutil/trace.go:171","msg":"trace[1192227854] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"137.379015ms","start":"2026-08-11T11:15:24.930955Z","end":"2026-08-11T11:15:25.068334Z","steps":["trace[1192227854] 'read index received' (duration: 137.1606ms)","trace[1192227854] 'applied index is now lower than readState.Index' (duration: 216.775µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:25.068524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.549519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cfkcr\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-08-11T11:15:25.068547Z","caller":"traceutil/trace.go:171","msg":"trace[1784729363] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cfkcr; range_end:; response_count:1; response_revision:1340; }","duration":"137.644051ms","start":"2026-08-11T11:15:24.930896Z","end":"2026-08-11T11:15:25.06854Z","steps":["trace[1784729363] 'agreement among raft nodes before linearized reading' (duration: 137.513928ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:25.069085Z","caller":"traceutil/trace.go:171","msg":"trace[370610895] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"245.576672ms","start":"2026-08-11T11:15:24.823497Z","end":"2026-08-11T11:15:25.069073Z","steps":["trace[370610895] 'process raft request' (duration: 244.627939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:25.62933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:26.130362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:26.631337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:26.66719Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.585423959s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:15:26.667526Z","caller":"traceutil/trace.go:171","msg":"trace[108698895] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.585943011s","start":"2026-08-11T11:15:25.081563Z","end":"2026-08-11T11:15:26.667506Z","steps":["trace[108698895] 'process raft request' (duration: 1.585808518s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:26.667634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.081524Z","time spent":"1.586042494s","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:27.036008Z","caller":"traceutil/trace.go:171","msg":"trace[736990421] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.93001668s","start":"2026-08-11T11:15:25.10597Z","end":"2026-08-11T11:15:27.035987Z","steps":["trace[736990421] 'process raft request' (duration: 1.863556963s)","trace[736990421] 'compare' (duration: 66.260342ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:27.036122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.105928Z","time spent":"1.930134223s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:15:27.218651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.687185ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T11:15:27.21876Z","caller":"traceutil/trace.go:171","msg":"trace[844299930] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1397; }","duration":"2.090223435s","start":"2026-08-11T11:15:25.128519Z","end":"2026-08-11T11:15:27.218742Z","steps":["trace[844299930] 'read index received' (duration: 1.538783676s)","trace[844299930] 'applied index is now lower than readState.Index' (duration: 551.438309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:27.219088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.090552963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:15:27.219269Z","caller":"traceutil/trace.go:171","msg":"trace[1676548163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"2.090768528s","start":"2026-08-11T11:15:25.128478Z","end":"2026-08-11T11:15:27.219247Z","steps":["trace[1676548163] 'agreement among raft nodes before linearized reading' (duration: 2.090409169s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.219314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.12846Z","time spent":"2.09084346s","remote":"127.0.0.1:47372","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-11T11:15:27.528416Z","caller":"traceutil/trace.go:171","msg":"trace[258299694] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"850.370451ms","start":"2026-08-11T11:15:26.678035Z","end":"2026-08-11T11:15:27.528406Z","steps":["trace[258299694] 'process raft request' (duration: 850.181147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.528522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.678019Z","time spent":"850.441344ms","remote":"127.0.0.1:47290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:27.528365Z","caller":"traceutil/trace.go:171","msg":"trace[1318272912] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"851.641563ms","start":"2026-08-11T11:15:26.676683Z","end":"2026-08-11T11:15:27.528324Z","steps":["trace[1318272912] 'process raft request' (duration: 851.272574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.528742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.676651Z","time spent":"852.074584ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:27.528774Z","caller":"traceutil/trace.go:171","msg":"trace[1108696994] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"842.7066ms","start":"2026-08-11T11:15:26.686063Z","end":"2026-08-11T11:15:27.52877Z","steps":["trace[1108696994] 'process raft request' (duration: 842.227118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.5288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.686036Z","time spent":"842.749082ms","remote":"127.0.0.1:47610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:27.529278Z","caller":"traceutil/trace.go:171","msg":"trace[60864946] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"598.944083ms","start":"2026-08-11T11:15:26.930318Z","end":"2026-08-11T11:15:27.529262Z","steps":["trace[60864946] 'process raft request' (duration: 598.331647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.529315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175699293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:15:27.529383Z","caller":"traceutil/trace.go:171","msg":"trace[397576958] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1346; }","duration":"1.175777135s","start":"2026-08-11T11:15:26.353589Z","end":"2026-08-11T11:15:27.529367Z","steps":["trace[397576958] 'agreement among raft nodes before linearized reading' (duration: 1.175636242s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.529414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.353492Z","time spent":"1.175913258s","remote":"127.0.0.1:47664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:15:27.529444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.930282Z","time spent":"599.078596ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:15:27.529608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.216774ms","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-11T11:15:27.52963Z","caller":"traceutil/trace.go:171","msg":"trace[1354849814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"456.278506ms","start":"2026-08-11T11:15:27.073345Z","end":"2026-08-11T11:15:27.529624Z","steps":["trace[1354849814] 'agreement among raft nodes before linearized reading' (duration: 456.190444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.529648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:27.073323Z","time spent":"456.319827ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:15:27.529724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021056237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:15:27.529744Z","caller":"traceutil/trace.go:171","msg":"trace[1176745994] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1346; }","duration":"1.021099739s","start":"2026-08-11T11:15:26.508636Z","end":"2026-08-11T11:15:27.529736Z","steps":["trace[1176745994] 'agreement among raft nodes before linearized reading' (duration: 1.021069128s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.529766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.508617Z","time spent":"1.021144439s","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:15:27.530076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894861994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:15:27.530129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.180852124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T11:15:27.53012Z","caller":"traceutil/trace.go:171","msg":"trace[1455039451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"1.894902815s","start":"2026-08-11T11:15:25.635204Z","end":"2026-08-11T11:15:27.530107Z","steps":["trace[1455039451] 'agreement among raft nodes before linearized reading' (duration: 1.894838593s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:27.530151Z","caller":"traceutil/trace.go:171","msg":"trace[1321657973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"2.180909686s","start":"2026-08-11T11:15:25.349234Z","end":"2026-08-11T11:15:27.530144Z","steps":["trace[1321657973] 'agreement among raft nodes before linearized reading' (duration: 2.180848764s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.530172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.349216Z","time spent":"2.180951526s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:15:27.530172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.635084Z","time spent":"1.895074569s","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:15:27.530364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.393988088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T11:15:27.530385Z","caller":"traceutil/trace.go:171","msg":"trace[605137346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"2.39405301s","start":"2026-08-11T11:15:25.136326Z","end":"2026-08-11T11:15:27.530379Z","steps":["trace[605137346] 'agreement among raft nodes before linearized reading' (duration: 2.393994338s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.530381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411353558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:15:27.530402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.136304Z","time spent":"2.394093861s","remote":"127.0.0.1:47372","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-11T11:15:27.530422Z","caller":"traceutil/trace.go:171","msg":"trace[1870087211] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1346; }","duration":"1.41143292s","start":"2026-08-11T11:15:26.118978Z","end":"2026-08-11T11:15:27.530411Z","steps":["trace[1870087211] 'agreement among raft nodes before linearized reading' (duration: 1.411366189s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.530453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:26.11896Z","time spent":"1.411485271s","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:15:27.529193Z","caller":"traceutil/trace.go:171","msg":"trace[466216565] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1400; }","duration":"310.385858ms","start":"2026-08-11T11:15:27.218784Z","end":"2026-08-11T11:15:27.52917Z","steps":["trace[466216565] 'read index received' (duration: 309.187839ms)","trace[466216565] 'applied index is now lower than readState.Index' (duration: 1.196579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:27.530767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.394434559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:15:27.530803Z","caller":"traceutil/trace.go:171","msg":"trace[1796202015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"2.394504921s","start":"2026-08-11T11:15:25.136288Z","end":"2026-08-11T11:15:27.530793Z","steps":["trace[1796202015] 'agreement among raft nodes before linearized reading' (duration: 2.394423209s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.530822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:25.136268Z","time spent":"2.394549312s","remote":"127.0.0.1:47372","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-11T11:15:27.575378Z","caller":"traceutil/trace.go:171","msg":"trace[1608641277] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"352.026537ms","start":"2026-08-11T11:15:27.223319Z","end":"2026-08-11T11:15:27.575345Z","steps":["trace[1608641277] 'process raft request' (duration: 351.75961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:27.575762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:27.223294Z","time spent":"352.378346ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:29.191673Z","caller":"traceutil/trace.go:171","msg":"trace[1872913204] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"149.416906ms","start":"2026-08-11T11:15:29.042223Z","end":"2026-08-11T11:15:29.19164Z","steps":["trace[1872913204] 'process raft request' (duration: 149.147179ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:29.917971Z","caller":"traceutil/trace.go:171","msg":"trace[38292540] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1411; }","duration":"277.274753ms","start":"2026-08-11T11:15:29.640644Z","end":"2026-08-11T11:15:29.917918Z","steps":["trace[38292540] 'read index received' (duration: 21.322071ms)","trace[38292540] 'applied index is now lower than readState.Index' (duration: 255.951332ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:29.918126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.476858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:29.91817Z","caller":"traceutil/trace.go:171","msg":"trace[941231433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"277.579921ms","start":"2026-08-11T11:15:29.640576Z","end":"2026-08-11T11:15:29.918156Z","steps":["trace[941231433] 'agreement among raft nodes before linearized reading' (duration: 277.475978ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:29.91826Z","caller":"traceutil/trace.go:171","msg":"trace[1492147666] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"281.112129ms","start":"2026-08-11T11:15:29.637124Z","end":"2026-08-11T11:15:29.918236Z","steps":["trace[1492147666] 'process raft request' (duration: 274.586016ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:29.91833Z","caller":"traceutil/trace.go:171","msg":"trace[1137330163] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"280.420322ms","start":"2026-08-11T11:15:29.637853Z","end":"2026-08-11T11:15:29.918274Z","steps":["trace[1137330163] 'process raft request' (duration: 279.9482ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:29.918484Z","caller":"traceutil/trace.go:171","msg":"trace[3654538] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"280.469073ms","start":"2026-08-11T11:15:29.637905Z","end":"2026-08-11T11:15:29.918374Z","steps":["trace[3654538] 'process raft request' (duration: 279.967521ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:34.070038Z","caller":"traceutil/trace.go:171","msg":"trace[1096951380] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"121.564521ms","start":"2026-08-11T11:15:33.948445Z","end":"2026-08-11T11:15:34.07001Z","steps":["trace[1096951380] 'process raft request' (duration: 121.503789ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:34.070338Z","caller":"traceutil/trace.go:171","msg":"trace[1846031184] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"121.751045ms","start":"2026-08-11T11:15:33.948397Z","end":"2026-08-11T11:15:34.070148Z","steps":["trace[1846031184] 'process raft request' (duration: 109.175301ms)","trace[1846031184] 'compare' (duration: 12.218625ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:36.137895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527013785803301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:15:36.630205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.413321986s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:15:36.630491Z","caller":"traceutil/trace.go:171","msg":"trace[2069035319] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"1.413736917s","start":"2026-08-11T11:15:35.216734Z","end":"2026-08-11T11:15:36.630471Z","steps":["trace[2069035319] 'process raft request' (duration: 1.413627404s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:36.630603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:35.216702Z","time spent":"1.413839299s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:15:37.589919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.165388ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T11:15:37.590023Z","caller":"traceutil/trace.go:171","msg":"trace[698447667] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1451; }","duration":"1.952733076s","start":"2026-08-11T11:15:35.63727Z","end":"2026-08-11T11:15:37.590003Z","steps":["trace[698447667] 'read index received' (duration: 993.13376ms)","trace[698447667] 'applied index is now lower than readState.Index' (duration: 959.598046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:37.590262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952983722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:37.590331Z","caller":"traceutil/trace.go:171","msg":"trace[1871222830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"1.953095254s","start":"2026-08-11T11:15:35.637218Z","end":"2026-08-11T11:15:37.590313Z","steps":["trace[1871222830] 'agreement among raft nodes before linearized reading' (duration: 1.952954441s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:35.637196Z","time spent":"1.953178977s","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:15:37.59044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93325863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:15:37.590513Z","caller":"traceutil/trace.go:171","msg":"trace[1033909108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"1.933370033s","start":"2026-08-11T11:15:35.657125Z","end":"2026-08-11T11:15:37.590495Z","steps":["trace[1033909108] 'agreement among raft nodes before linearized reading' (duration: 1.933165978s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515564906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T11:15:37.590562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:35.657105Z","time spent":"1.933446124s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:15:37.590564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.14126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:37.590595Z","caller":"traceutil/trace.go:171","msg":"trace[1564113682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"1.51568941s","start":"2026-08-11T11:15:36.074886Z","end":"2026-08-11T11:15:37.590575Z","steps":["trace[1564113682] 'agreement among raft nodes before linearized reading' (duration: 1.515515026s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:36.074841Z","time spent":"1.515789552s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T11:15:37.590665Z","caller":"traceutil/trace.go:171","msg":"trace[429771855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"492.251973ms","start":"2026-08-11T11:15:37.098359Z","end":"2026-08-11T11:15:37.590611Z","steps":["trace[429771855] 'agreement among raft nodes before linearized reading' (duration: 492.13463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:37.098339Z","time spent":"492.357006ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:15:37.590804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.114488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:15:37.590858Z","caller":"traceutil/trace.go:171","msg":"trace[453333337] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1393; }","duration":"654.17248ms","start":"2026-08-11T11:15:36.936672Z","end":"2026-08-11T11:15:37.590844Z","steps":["trace[453333337] 'agreement among raft nodes before linearized reading' (duration: 654.078157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.082772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T11:15:37.590942Z","caller":"traceutil/trace.go:171","msg":"trace[1990827998] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"217.180505ms","start":"2026-08-11T11:15:37.37375Z","end":"2026-08-11T11:15:37.59093Z","steps":["trace[1990827998] 'agreement among raft nodes before linearized reading' (duration: 217.053272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.890327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:37.591016Z","caller":"traceutil/trace.go:171","msg":"trace[426590135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1393; }","duration":"810.944029ms","start":"2026-08-11T11:15:36.780058Z","end":"2026-08-11T11:15:37.591002Z","steps":["trace[426590135] 'agreement among raft nodes before linearized reading' (duration: 810.871507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.590906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:36.936618Z","time spent":"654.275043ms","remote":"127.0.0.1:47204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:15:37.591087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543354909s","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-11T11:15:37.591132Z","caller":"traceutil/trace.go:171","msg":"trace[624453071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1393; }","duration":"1.543425382s","start":"2026-08-11T11:15:36.047693Z","end":"2026-08-11T11:15:37.591118Z","steps":["trace[624453071] 'agreement among raft nodes before linearized reading' (duration: 1.543308259s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.591135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898059853s","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-11T11:15:37.591173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603843178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-11T11:15:37.59121Z","caller":"traceutil/trace.go:171","msg":"trace[943115907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"1.898169936s","start":"2026-08-11T11:15:35.693027Z","end":"2026-08-11T11:15:37.591197Z","steps":["trace[943115907] 'agreement among raft nodes before linearized reading' (duration: 1.898016162s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:37.591221Z","caller":"traceutil/trace.go:171","msg":"trace[1381029082] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"1.60391808s","start":"2026-08-11T11:15:35.987292Z","end":"2026-08-11T11:15:37.59121Z","steps":["trace[1381029082] 'agreement among raft nodes before linearized reading' (duration: 1.603800667s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.591252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:35.693009Z","time spent":"1.898231597s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:15:37.591181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:36.047673Z","time spent":"1.543497563s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T11:15:37.591257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:35.987276Z","time spent":"1.603972251s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-11T11:15:37.590967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516178222s","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-11T11:15:37.591373Z","caller":"traceutil/trace.go:171","msg":"trace[1860619747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"1.516637114s","start":"2026-08-11T11:15:36.074723Z","end":"2026-08-11T11:15:37.59136Z","steps":["trace[1860619747] 'agreement among raft nodes before linearized reading' (duration: 1.516122501s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:37.591424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:36.074707Z","time spent":"1.516704935s","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:15:37.591068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:36.780033Z","time spent":"811.021841ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:15:44.126065Z","caller":"traceutil/trace.go:171","msg":"trace[1521451477] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"136.639007ms","start":"2026-08-11T11:15:43.989406Z","end":"2026-08-11T11:15:44.126045Z","steps":["trace[1521451477] 'process raft request' (duration: 136.514463ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:44.410027Z","caller":"traceutil/trace.go:171","msg":"trace[2031785766] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"229.575304ms","start":"2026-08-11T11:15:44.180437Z","end":"2026-08-11T11:15:44.410012Z","steps":["trace[2031785766] 'process raft request' (duration: 229.457241ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:47.84353Z","caller":"traceutil/trace.go:171","msg":"trace[1907379162] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"216.458367ms","start":"2026-08-11T11:15:47.627053Z","end":"2026-08-11T11:15:47.843512Z","steps":["trace[1907379162] 'process raft request' (duration: 216.353824ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:47.848096Z","caller":"traceutil/trace.go:171","msg":"trace[1084799007] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1517; }","duration":"197.600747ms","start":"2026-08-11T11:15:47.650484Z","end":"2026-08-11T11:15:47.848085Z","steps":["trace[1084799007] 'read index received' (duration: 192.971221ms)","trace[1084799007] 'applied index is now lower than readState.Index' (duration: 4.627066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:47.848342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.736147ms","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-11T11:15:47.848377Z","caller":"traceutil/trace.go:171","msg":"trace[1079897278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1458; }","duration":"178.782338ms","start":"2026-08-11T11:15:47.669586Z","end":"2026-08-11T11:15:47.848369Z","steps":["trace[1079897278] 'agreement among raft nodes before linearized reading' (duration: 178.666525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:47.848386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.07951ms","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-11T11:15:47.848372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.873213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:15:47.848417Z","caller":"traceutil/trace.go:171","msg":"trace[1254610715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1458; }","duration":"107.154592ms","start":"2026-08-11T11:15:47.741254Z","end":"2026-08-11T11:15:47.848409Z","steps":["trace[1254610715] 'agreement among raft nodes before linearized reading' (duration: 107.062649ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:47.848439Z","caller":"traceutil/trace.go:171","msg":"trace[1774710381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1458; }","duration":"197.978386ms","start":"2026-08-11T11:15:47.650444Z","end":"2026-08-11T11:15:47.848422Z","steps":["trace[1774710381] 'agreement among raft nodes before linearized reading' (duration: 197.779641ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:47.848479Z","caller":"traceutil/trace.go:171","msg":"trace[1102948454] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"221.093522ms","start":"2026-08-11T11:15:47.627378Z","end":"2026-08-11T11:15:47.848472Z","steps":["trace[1102948454] 'process raft request' (duration: 220.61302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:47.848459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.353012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T11:15:47.848528Z","caller":"traceutil/trace.go:171","msg":"trace[550684250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1458; }","duration":"173.447824ms","start":"2026-08-11T11:15:47.675067Z","end":"2026-08-11T11:15:47.848515Z","steps":["trace[550684250] 'agreement among raft nodes before linearized reading' (duration: 173.323141ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:47.848571Z","caller":"traceutil/trace.go:171","msg":"trace[2070333691] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"142.958334ms","start":"2026-08-11T11:15:47.705575Z","end":"2026-08-11T11:15:47.848534Z","steps":["trace[2070333691] 'process raft request' (duration: 142.474192ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:56.668971Z","caller":"traceutil/trace.go:171","msg":"trace[1842510713] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"276.684638ms","start":"2026-08-11T11:15:56.392263Z","end":"2026-08-11T11:15:56.668948Z","steps":["trace[1842510713] 'process raft request' (duration: 276.546454ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:00.475337Z","caller":"traceutil/trace.go:171","msg":"trace[1105171455] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"117.619312ms","start":"2026-08-11T11:16:00.357671Z","end":"2026-08-11T11:16:00.47529Z","steps":["trace[1105171455] 'process raft request' (duration: 117.294584ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:02.212053Z","caller":"traceutil/trace.go:171","msg":"trace[1413022505] linearizableReadLoop","detail":"{readStateIndex:1589; appliedIndex:1588; }","duration":"133.499019ms","start":"2026-08-11T11:16:02.078536Z","end":"2026-08-11T11:16:02.212035Z","steps":["trace[1413022505] 'read index received' (duration: 71.629576ms)","trace[1413022505] 'applied index is now lower than readState.Index' (duration: 61.868413ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:02.212171Z","caller":"traceutil/trace.go:171","msg":"trace[1010917376] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"136.858913ms","start":"2026-08-11T11:16:02.075285Z","end":"2026-08-11T11:16:02.212144Z","steps":["trace[1010917376] 'process raft request' (duration: 74.885497ms)","trace[1010917376] 'compare' (duration: 61.771181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:02.212436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.888008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:16:02.212522Z","caller":"traceutil/trace.go:171","msg":"trace[1256143971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1524; }","duration":"134.011892ms","start":"2026-08-11T11:16:02.078493Z","end":"2026-08-11T11:16:02.212505Z","steps":["trace[1256143971] 'agreement among raft nodes before linearized reading' (duration: 133.778546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:02.212435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.345785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-11T11:16:02.212567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.240308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T11:16:02.212597Z","caller":"traceutil/trace.go:171","msg":"trace[1257302019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1524; }","duration":"133.535609ms","start":"2026-08-11T11:16:02.079045Z","end":"2026-08-11T11:16:02.21258Z","steps":["trace[1257302019] 'agreement among raft nodes before linearized reading' (duration: 133.241732ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:02.212628Z","caller":"traceutil/trace.go:171","msg":"trace[1162086510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1524; }","duration":"132.314969ms","start":"2026-08-11T11:16:02.080295Z","end":"2026-08-11T11:16:02.21261Z","steps":["trace[1162086510] 'agreement among raft nodes before linearized reading' (duration: 132.150955ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.117757Z","caller":"traceutil/trace.go:171","msg":"trace[575153804] linearizableReadLoop","detail":"{readStateIndex:1711; appliedIndex:1710; }","duration":"108.355715ms","start":"2026-08-11T11:16:28.009383Z","end":"2026-08-11T11:16:28.117739Z","steps":["trace[575153804] 'read index received' (duration: 108.238652ms)","trace[575153804] 'applied index is now lower than readState.Index' (duration: 116.153µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:28.117791Z","caller":"traceutil/trace.go:171","msg":"trace[1387501007] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"126.066557ms","start":"2026-08-11T11:16:27.991708Z","end":"2026-08-11T11:16:28.117775Z","steps":["trace[1387501007] 'process raft request' (duration: 125.909573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.117923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.532189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T11:16:28.117956Z","caller":"traceutil/trace.go:171","msg":"trace[911557198] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1641; }","duration":"108.612421ms","start":"2026-08-11T11:16:28.009336Z","end":"2026-08-11T11:16:28.117948Z","steps":["trace[911557198] 'agreement among raft nodes before linearized reading' (duration: 108.483828ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.25518Z","caller":"traceutil/trace.go:171","msg":"trace[1101824710] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"131.980184ms","start":"2026-08-11T11:16:28.123171Z","end":"2026-08-11T11:16:28.255151Z","steps":["trace[1101824710] 'process raft request' (duration: 54.832658ms)","trace[1101824710] 'compare' (duration: 76.968341ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:28.601694Z","caller":"traceutil/trace.go:171","msg":"trace[1757525806] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"118.272892ms","start":"2026-08-11T11:16:28.483385Z","end":"2026-08-11T11:16:28.601658Z","steps":["trace[1757525806] 'process raft request' (duration: 41.838194ms)","trace[1757525806] 'compare' (duration: 76.323265ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:28.642207Z","caller":"traceutil/trace.go:171","msg":"trace[861411417] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"158.481016ms","start":"2026-08-11T11:16:28.483706Z","end":"2026-08-11T11:16:28.642187Z","steps":["trace[861411417] 'process raft request' (duration: 158.339582ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:46.875577Z","caller":"traceutil/trace.go:171","msg":"trace[697698963] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1801; }","duration":"126.57463ms","start":"2026-08-11T11:16:46.748981Z","end":"2026-08-11T11:16:46.875556Z","steps":["trace[697698963] 'read index received' (duration: 65.411063ms)","trace[697698963] 'applied index is now lower than readState.Index' (duration: 61.162617ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:46.875657Z","caller":"traceutil/trace.go:171","msg":"trace[1915932608] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"237.837667ms","start":"2026-08-11T11:16:46.637782Z","end":"2026-08-11T11:16:46.87562Z","steps":["trace[1915932608] 'process raft request' (duration: 176.727122ms)","trace[1915932608] 'compare' (duration: 60.925361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:46.875795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.796475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:16:46.875841Z","caller":"traceutil/trace.go:171","msg":"trace[577478376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1728; }","duration":"126.884167ms","start":"2026-08-11T11:16:46.748943Z","end":"2026-08-11T11:16:46.875828Z","steps":["trace[577478376] 'agreement among raft nodes before linearized reading' (duration: 126.730724ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.074544Z","caller":"traceutil/trace.go:171","msg":"trace[524541696] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"193.724185ms","start":"2026-08-11T11:16:46.880767Z","end":"2026-08-11T11:16:47.074491Z","steps":["trace[524541696] 'process raft request' (duration: 110.259772ms)","trace[524541696] 'compare' (duration: 83.234697ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:47.318073Z","caller":"traceutil/trace.go:171","msg":"trace[1627031842] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"141.090041ms","start":"2026-08-11T11:16:47.176966Z","end":"2026-08-11T11:16:47.318056Z","steps":["trace[1627031842] 'process raft request' (duration: 141.06292ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.318146Z","caller":"traceutil/trace.go:171","msg":"trace[1617819023] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"239.429836ms","start":"2026-08-11T11:16:47.078666Z","end":"2026-08-11T11:16:47.318096Z","steps":["trace[1617819023] 'process raft request' (duration: 165.211634ms)","trace[1617819023] 'compare' (duration: 74.058638ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:13.386363Z","caller":"traceutil/trace.go:171","msg":"trace[159143768] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"108.508338ms","start":"2026-08-11T11:17:13.277833Z","end":"2026-08-11T11:17:13.386341Z","steps":["trace[159143768] 'process raft request' (duration: 108.20515ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:18:51.847091Z","caller":"traceutil/trace.go:171","msg":"trace[1173105184] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"153.152328ms","start":"2026-08-11T11:18:51.693915Z","end":"2026-08-11T11:18:51.847067Z","steps":["trace[1173105184] 'process raft request' (duration: 153.001384ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:18:57.780382Z","caller":"traceutil/trace.go:171","msg":"trace[761434651] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"115.407734ms","start":"2026-08-11T11:18:57.664952Z","end":"2026-08-11T11:18:57.780359Z","steps":["trace[761434651] 'process raft request' (duration: 115.174858ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:19:25.92897Z","caller":"traceutil/trace.go:171","msg":"trace[6447648] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2593; }","duration":"626.68846ms","start":"2026-08-11T11:19:25.302263Z","end":"2026-08-11T11:19:25.928951Z","steps":["trace[6447648] 'read index received' (duration: 626.465995ms)","trace[6447648] 'applied index is now lower than readState.Index' (duration: 221.385µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:19:25.930052Z","caller":"traceutil/trace.go:171","msg":"trace[366318851] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"640.479845ms","start":"2026-08-11T11:19:25.289561Z","end":"2026-08-11T11:19:25.930041Z","steps":["trace[366318851] 'process raft request' (duration: 639.215933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.930322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.289548Z","time spent":"640.526815ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:19:25.930545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.29005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-08-11T11:19:25.930566Z","caller":"traceutil/trace.go:171","msg":"trace[1674149047] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:2488; }","duration":"628.338031ms","start":"2026-08-11T11:19:25.302221Z","end":"2026-08-11T11:19:25.930559Z","steps":["trace[1674149047] 'agreement among raft nodes before linearized reading' (duration: 628.211218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.930581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.302205Z","time spent":"628.372112ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":317313,"request content":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" "} {"level":"warn","ts":"2026-08-11T11:19:25.93144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.057864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1\" ","response":"range_response_count:1 size:32868"} {"level":"info","ts":"2026-08-11T11:19:25.931506Z","caller":"traceutil/trace.go:171","msg":"trace[1300417721] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1; range_end:; response_count:1; response_revision:2488; }","duration":"618.183527ms","start":"2026-08-11T11:19:25.31331Z","end":"2026-08-11T11:19:25.931493Z","steps":["trace[1300417721] 'agreement among raft nodes before linearized reading' (duration: 618.050644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.931539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.313297Z","time spent":"618.234889ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":32891,"request content":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1\" "} {"level":"warn","ts":"2026-08-11T11:19:25.931758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.444037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:19:25.931804Z","caller":"traceutil/trace.go:171","msg":"trace[1888765608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2488; }","duration":"298.503928ms","start":"2026-08-11T11:19:25.633293Z","end":"2026-08-11T11:19:25.931797Z","steps":["trace[1888765608] 'agreement among raft nodes before linearized reading' (duration: 298.448137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.931924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.606451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T11:19:25.931955Z","caller":"traceutil/trace.go:171","msg":"trace[773752978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2488; }","duration":"322.673472ms","start":"2026-08-11T11:19:25.609275Z","end":"2026-08-11T11:19:25.931949Z","steps":["trace[773752978] 'agreement among raft nodes before linearized reading' (duration: 322.612071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.9322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.609255Z","time spent":"322.937879ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:19:25.932516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.770583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-11T11:19:25.932548Z","caller":"traceutil/trace.go:171","msg":"trace[1580063883] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2488; }","duration":"486.834185ms","start":"2026-08-11T11:19:25.445708Z","end":"2026-08-11T11:19:25.932542Z","steps":["trace[1580063883] 'agreement among raft nodes before linearized reading' (duration: 486.769164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.932578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.44569Z","time spent":"486.880306ms","remote":"127.0.0.1:47372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}