{"level":"warn","ts":"2026-08-13T00:52:46.495069Z","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-13T00:52:46.904033Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-13T00:52:46.904229Z","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-13T00:52:46.904269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-13T00:52:46.904349Z","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-13T00:52:46.905719Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-08-13T00:52:47.072107Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-13T00:52:47.525541Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"452.948742ms"} {"level":"info","ts":"2026-08-13T00:52:47.550696Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-08-13T00:52:47.550805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T00:52:47.550841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-08-13T00:52:47.550857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T00:52:47.55088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-08-13T00:52:47.550953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-08-13T00:52:47.565621Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T00:52:47.56735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T00:52:47.569839Z","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-13T00:52:47.577433Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T00:52:47.580035Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T00:52:47.579738Z","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-13T00:52:47.580481Z","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-13T00:52:47.580505Z","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-13T00:52:47.582434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-08-13T00:52:47.582587Z","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-13T00:52:47.582591Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-13T00:52:47.58267Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-13T00:52:47.582723Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-13T00:52:47.582837Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T00:52:47.582838Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T00:52:47.652548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T00:52:47.652657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T00:52:47.652693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-08-13T00:52:47.652712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-08-13T00:52:47.652721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-13T00:52:47.652736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-08-13T00:52:47.652756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-13T00:52:47.655058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T00:52:47.655062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:52:47.655127Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:52:47.655261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:52:47.655998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T00:52:47.656027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T00:52:47.656329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:52:47.656534Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:52:47.657277Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T00:52:47.657468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:52:47.658855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-08-13T00:53:23.436112Z","caller":"traceutil/trace.go:171","msg":"trace[901477469] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"288.715375ms","start":"2026-08-13T00:53:23.147378Z","end":"2026-08-13T00:53:23.436094Z","steps":["trace[901477469] 'read index received' (duration: 288.50291ms)","trace[901477469] 'applied index is now lower than readState.Index' (duration: 211.705µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:53:23.436221Z","caller":"traceutil/trace.go:171","msg":"trace[504854463] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"576.078454ms","start":"2026-08-13T00:53:22.860135Z","end":"2026-08-13T00:53:23.436214Z","steps":["trace[504854463] 'process raft request' (duration: 575.783597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:23.43635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.264668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:53:23.436436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.048433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-08-13T00:53:23.436454Z","caller":"traceutil/trace.go:171","msg":"trace[361613670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"153.431773ms","start":"2026-08-13T00:53:23.283Z","end":"2026-08-13T00:53:23.436432Z","steps":["trace[361613670] 'agreement among raft nodes before linearized reading' (duration: 153.272229ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:23.436473Z","caller":"traceutil/trace.go:171","msg":"trace[540309802] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:448; }","duration":"289.134926ms","start":"2026-08-13T00:53:23.147326Z","end":"2026-08-13T00:53:23.436461Z","steps":["trace[540309802] 'agreement among raft nodes before linearized reading' (duration: 289.009182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:23.437183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:22.860121Z","time spent":"576.120575ms","remote":"127.0.0.1:55594","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-13T00:53:26.344836Z","caller":"traceutil/trace.go:171","msg":"trace[760193432] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"274.257229ms","start":"2026-08-13T00:53:26.070551Z","end":"2026-08-13T00:53:26.344809Z","steps":["trace[760193432] 'process raft request' (duration: 274.106734ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.352305Z","caller":"traceutil/trace.go:171","msg":"trace[17128222] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"281.501207ms","start":"2026-08-13T00:53:26.070778Z","end":"2026-08-13T00:53:26.352279Z","steps":["trace[17128222] 'process raft request' (duration: 281.381424ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.352283Z","caller":"traceutil/trace.go:171","msg":"trace[435622124] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"281.518227ms","start":"2026-08-13T00:53:26.070739Z","end":"2026-08-13T00:53:26.352257Z","steps":["trace[435622124] 'process raft request' (duration: 281.311542ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.352448Z","caller":"traceutil/trace.go:171","msg":"trace[1275653413] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"270.48868ms","start":"2026-08-13T00:53:26.081934Z","end":"2026-08-13T00:53:26.352423Z","steps":["trace[1275653413] 'process raft request' (duration: 270.279945ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.352536Z","caller":"traceutil/trace.go:171","msg":"trace[1369391426] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"268.590501ms","start":"2026-08-13T00:53:26.083937Z","end":"2026-08-13T00:53:26.352527Z","steps":["trace[1369391426] 'process raft request' (duration: 268.314304ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.354484Z","caller":"traceutil/trace.go:171","msg":"trace[118132447] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"269.926886ms","start":"2026-08-13T00:53:26.084392Z","end":"2026-08-13T00:53:26.354319Z","steps":["trace[118132447] 'process raft request' (duration: 268.005856ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.354545Z","caller":"traceutil/trace.go:171","msg":"trace[771163024] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"265.176593ms","start":"2026-08-13T00:53:26.089117Z","end":"2026-08-13T00:53:26.354294Z","steps":["trace[771163024] 'process raft request' (duration: 264.328681ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.354599Z","caller":"traceutil/trace.go:171","msg":"trace[2107609258] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"256.60069ms","start":"2026-08-13T00:53:26.0978Z","end":"2026-08-13T00:53:26.354401Z","steps":["trace[2107609258] 'process raft request' (duration: 256.143188ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.36439Z","caller":"traceutil/trace.go:171","msg":"trace[1642144546] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"260.153381ms","start":"2026-08-13T00:53:26.094141Z","end":"2026-08-13T00:53:26.354294Z","steps":["trace[1642144546] 'process raft request' (duration: 259.539696ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.364491Z","caller":"traceutil/trace.go:171","msg":"trace[227967043] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"210.109189ms","start":"2026-08-13T00:53:26.154375Z","end":"2026-08-13T00:53:26.364484Z","steps":["trace[227967043] 'process raft request' (duration: 209.452181ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.364446Z","caller":"traceutil/trace.go:171","msg":"trace[207651923] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"246.127816ms","start":"2026-08-13T00:53:26.118295Z","end":"2026-08-13T00:53:26.364423Z","steps":["trace[207651923] 'process raft request' (duration: 245.332365ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:26.364808Z","caller":"traceutil/trace.go:171","msg":"trace[380992143] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:507; }","duration":"154.303646ms","start":"2026-08-13T00:53:26.210477Z","end":"2026-08-13T00:53:26.364781Z","steps":["trace[380992143] 'read index received' (duration: 134.295516ms)","trace[380992143] 'applied index is now lower than readState.Index' (duration: 20.00664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:26.364947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.45552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-13T00:53:26.364977Z","caller":"traceutil/trace.go:171","msg":"trace[941528878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"154.536812ms","start":"2026-08-13T00:53:26.210433Z","end":"2026-08-13T00:53:26.36497Z","steps":["trace[941528878] 'agreement among raft nodes before linearized reading' (duration: 154.417409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:26.372436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.892673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T00:53:26.37246Z","caller":"traceutil/trace.go:171","msg":"trace[451542023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"161.929645ms","start":"2026-08-13T00:53:26.210525Z","end":"2026-08-13T00:53:26.372455Z","steps":["trace[451542023] 'agreement among raft nodes before linearized reading' (duration: 161.835482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.348617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.995568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:53:27.348724Z","caller":"traceutil/trace.go:171","msg":"trace[139872120] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"831.444422ms","start":"2026-08-13T00:53:26.517265Z","end":"2026-08-13T00:53:27.34871Z","steps":["trace[139872120] 'process raft request' (duration: 440.096685ms)","trace[139872120] 'compare' (duration: 390.854994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:27.348793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:26.517192Z","time spent":"831.558365ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:27.349537Z","caller":"traceutil/trace.go:171","msg":"trace[1521183433] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"816.816191ms","start":"2026-08-13T00:53:26.532706Z","end":"2026-08-13T00:53:27.349523Z","steps":["trace[1521183433] 'process raft request' (duration: 816.662017ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:27.349593Z","caller":"traceutil/trace.go:171","msg":"trace[156393743] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"823.885645ms","start":"2026-08-13T00:53:26.52568Z","end":"2026-08-13T00:53:27.349565Z","steps":["trace[156393743] 'process raft request' (duration: 823.585977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.349754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:26.532685Z","time spent":"817.031206ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:53:27.349906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:26.525663Z","time spent":"824.160881ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:27.349958Z","caller":"traceutil/trace.go:171","msg":"trace[13317620] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:553; }","duration":"126.670146ms","start":"2026-08-13T00:53:27.22327Z","end":"2026-08-13T00:53:27.34994Z","steps":["trace[13317620] 'read index received' (duration: 126.663476ms)","trace[13317620] 'applied index is now lower than readState.Index' (duration: 5.39µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:53:27.349757Z","caller":"traceutil/trace.go:171","msg":"trace[1549307698] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"828.649059ms","start":"2026-08-13T00:53:26.520816Z","end":"2026-08-13T00:53:27.349465Z","steps":["trace[1549307698] 'process raft request' (duration: 828.154237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.350121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.841121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:27.350209Z","caller":"traceutil/trace.go:171","msg":"trace[249536146] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:529; }","duration":"126.964774ms","start":"2026-08-13T00:53:27.223228Z","end":"2026-08-13T00:53:27.350193Z","steps":["trace[249536146] 'agreement among raft nodes before linearized reading' (duration: 126.81626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.350142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.869432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:27.350271Z","caller":"traceutil/trace.go:171","msg":"trace[174203793] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:529; }","duration":"127.026625ms","start":"2026-08-13T00:53:27.223228Z","end":"2026-08-13T00:53:27.350255Z","steps":["trace[174203793] 'agreement among raft nodes before linearized reading' (duration: 126.779909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.350307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.038234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:53:27.350374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.454274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:27.350396Z","caller":"traceutil/trace.go:171","msg":"trace[1127335351] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:529; }","duration":"110.151998ms","start":"2026-08-13T00:53:27.240227Z","end":"2026-08-13T00:53:27.350379Z","steps":["trace[1127335351] 'agreement among raft nodes before linearized reading' (duration: 110.007923ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:27.350429Z","caller":"traceutil/trace.go:171","msg":"trace[784714352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:529; }","duration":"110.531928ms","start":"2026-08-13T00:53:27.239884Z","end":"2026-08-13T00:53:27.350416Z","steps":["trace[784714352] 'agreement among raft nodes before linearized reading' (duration: 110.466565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.3503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.845875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:27.350543Z","caller":"traceutil/trace.go:171","msg":"trace[1760305879] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:529; }","duration":"126.142693ms","start":"2026-08-13T00:53:27.224383Z","end":"2026-08-13T00:53:27.350525Z","steps":["trace[1760305879] 'agreement among raft nodes before linearized reading' (duration: 125.846455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.35066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.737203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:27.350708Z","caller":"traceutil/trace.go:171","msg":"trace[797343005] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:529; }","duration":"110.813345ms","start":"2026-08-13T00:53:27.239882Z","end":"2026-08-13T00:53:27.350695Z","steps":["trace[797343005] 'agreement among raft nodes before linearized reading' (duration: 110.749503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:27.351234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:26.520796Z","time spent":"829.259744ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:33.879253Z","caller":"traceutil/trace.go:171","msg":"trace[714634871] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"388.504513ms","start":"2026-08-13T00:53:33.490723Z","end":"2026-08-13T00:53:33.879227Z","steps":["trace[714634871] 'process raft request' (duration: 388.312768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:33.879478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:33.490711Z","time spent":"388.664657ms","remote":"127.0.0.1:55594","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-13T00:53:34.10587Z","caller":"traceutil/trace.go:171","msg":"trace[696421982] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"501.576756ms","start":"2026-08-13T00:53:33.604263Z","end":"2026-08-13T00:53:34.10584Z","steps":["trace[696421982] 'read index received' (duration: 274.70582ms)","trace[696421982] 'applied index is now lower than readState.Index' (duration: 226.869626ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:53:34.106263Z","caller":"traceutil/trace.go:171","msg":"trace[916754938] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"531.464644ms","start":"2026-08-13T00:53:33.574782Z","end":"2026-08-13T00:53:34.106247Z","steps":["trace[916754938] 'process raft request' (duration: 530.094149ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:34.106225Z","caller":"traceutil/trace.go:171","msg":"trace[2025529490] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"520.687953ms","start":"2026-08-13T00:53:33.585433Z","end":"2026-08-13T00:53:34.106121Z","steps":["trace[2025529490] 'process raft request' (duration: 519.933433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.106375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:33.574748Z","time spent":"531.560026ms","remote":"127.0.0.1:55594","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-13T00:53:34.106491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.205951ms","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-13T00:53:34.106582Z","caller":"traceutil/trace.go:171","msg":"trace[1539784900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"502.463088ms","start":"2026-08-13T00:53:33.604096Z","end":"2026-08-13T00:53:34.106559Z","steps":["trace[1539784900] 'agreement among raft nodes before linearized reading' (duration: 502.188191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.106645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:33.604066Z","time spent":"502.564941ms","remote":"127.0.0.1:55594","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-13T00:53:34.106759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.20675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g9xnn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-13T00:53:34.106814Z","caller":"traceutil/trace.go:171","msg":"trace[1568945531] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g9xnn; range_end:; response_count:1; response_revision:601; }","duration":"291.325533ms","start":"2026-08-13T00:53:33.815473Z","end":"2026-08-13T00:53:34.106799Z","steps":["trace[1568945531] 'agreement among raft nodes before linearized reading' (duration: 290.665656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.106788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:33.585395Z","time spent":"521.001351ms","remote":"127.0.0.1:55430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:34.597558Z","caller":"traceutil/trace.go:171","msg":"trace[468979898] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"473.3677ms","start":"2026-08-13T00:53:34.124162Z","end":"2026-08-13T00:53:34.597529Z","steps":["trace[468979898] 'process raft request' (duration: 473.217576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.597748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.124114Z","time spent":"473.534465ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:34.689209Z","caller":"traceutil/trace.go:171","msg":"trace[602710922] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"405.774502ms","start":"2026-08-13T00:53:34.283398Z","end":"2026-08-13T00:53:34.689172Z","steps":["trace[602710922] 'read index received' (duration: 314.174258ms)","trace[602710922] 'applied index is now lower than readState.Index' (duration: 91.599414ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:53:34.689238Z","caller":"traceutil/trace.go:171","msg":"trace[539122158] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"518.338072ms","start":"2026-08-13T00:53:34.17087Z","end":"2026-08-13T00:53:34.689208Z","steps":["trace[539122158] 'process raft request' (duration: 518.148287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.689381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.17084Z","time spent":"518.462546ms","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:53:34.689442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.029928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:34.689521Z","caller":"traceutil/trace.go:171","msg":"trace[167416358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"406.163452ms","start":"2026-08-13T00:53:34.283341Z","end":"2026-08-13T00:53:34.689505Z","steps":["trace[167416358] 'agreement among raft nodes before linearized reading' (duration: 405.978337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.68957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.283319Z","time spent":"406.244254ms","remote":"127.0.0.1:55330","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-13T00:53:34.689563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.776195ms","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-13T00:53:34.689627Z","caller":"traceutil/trace.go:171","msg":"trace[732741936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"243.901248ms","start":"2026-08-13T00:53:34.445705Z","end":"2026-08-13T00:53:34.689606Z","steps":["trace[732741936] 'agreement among raft nodes before linearized reading' (duration: 243.641562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:34.689611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.192998ms","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-13T00:53:34.6897Z","caller":"traceutil/trace.go:171","msg":"trace[1468110288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"241.320581ms","start":"2026-08-13T00:53:34.448356Z","end":"2026-08-13T00:53:34.689677Z","steps":["trace[1468110288] 'agreement among raft nodes before linearized reading' (duration: 241.097415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:35.991509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214891752s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:53:35.992111Z","caller":"traceutil/trace.go:171","msg":"trace[2001577465] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.298720203s","start":"2026-08-13T00:53:34.693356Z","end":"2026-08-13T00:53:35.992077Z","steps":["trace[2001577465] 'process raft request' (duration: 83.133023ms)","trace[2001577465] 'compare' (duration: 1.213962988s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:35.992236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.693338Z","time spent":"1.298839317s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:35.992952Z","caller":"traceutil/trace.go:171","msg":"trace[1200488524] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.299127685s","start":"2026-08-13T00:53:34.693813Z","end":"2026-08-13T00:53:35.99294Z","steps":["trace[1200488524] 'process raft request' (duration: 1.298209911s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:35.993027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.693804Z","time spent":"1.299181927s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:35.993431Z","caller":"traceutil/trace.go:171","msg":"trace[1433053908] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"1.298891809s","start":"2026-08-13T00:53:34.693894Z","end":"2026-08-13T00:53:35.992786Z","steps":["trace[1433053908] 'read index received' (duration: 82.625461ms)","trace[1433053908] 'applied index is now lower than readState.Index' (duration: 1.216264948s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:35.99348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299529265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-08-13T00:53:35.99357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.339042ms","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-13T00:53:35.993606Z","caller":"traceutil/trace.go:171","msg":"trace[19273498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"110.376643ms","start":"2026-08-13T00:53:35.883218Z","end":"2026-08-13T00:53:35.993595Z","steps":["trace[19273498] 'agreement among raft nodes before linearized reading' (duration: 110.300461ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:35.993638Z","caller":"traceutil/trace.go:171","msg":"trace[1587196736] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:606; }","duration":"1.299741461s","start":"2026-08-13T00:53:34.693833Z","end":"2026-08-13T00:53:35.993574Z","steps":["trace[1587196736] 'agreement among raft nodes before linearized reading' (duration: 1.299263338s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:35.993761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:34.693807Z","time spent":"1.299939816s","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-13T00:53:36.499934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352501301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:53:36.902316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.95731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:53:36.902567Z","caller":"traceutil/trace.go:171","msg":"trace[224887125] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"904.120433ms","start":"2026-08-13T00:53:35.998394Z","end":"2026-08-13T00:53:36.902515Z","steps":["trace[224887125] 'process raft request' (duration: 548.890787ms)","trace[224887125] 'compare' (duration: 354.339523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:36.902783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:35.998367Z","time spent":"904.295357ms","remote":"127.0.0.1:55594","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-13T00:53:36.904537Z","caller":"traceutil/trace.go:171","msg":"trace[1936946033] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"905.314694ms","start":"2026-08-13T00:53:35.999203Z","end":"2026-08-13T00:53:36.904517Z","steps":["trace[1936946033] 'read index received' (duration: 548.113077ms)","trace[1936946033] 'applied index is now lower than readState.Index' (duration: 357.200507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:36.904696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.51747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-13T00:53:36.904733Z","caller":"traceutil/trace.go:171","msg":"trace[408201455] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:607; }","duration":"905.639383ms","start":"2026-08-13T00:53:35.999083Z","end":"2026-08-13T00:53:36.904722Z","steps":["trace[408201455] 'agreement among raft nodes before linearized reading' (duration: 905.55562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:36.90477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:35.999055Z","time spent":"905.705795ms","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-13T00:53:36.905062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.492317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:36.905098Z","caller":"traceutil/trace.go:171","msg":"trace[1529903565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"621.553068ms","start":"2026-08-13T00:53:36.283534Z","end":"2026-08-13T00:53:36.905087Z","steps":["trace[1529903565] 'agreement among raft nodes before linearized reading' (duration: 621.501607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:36.905122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:36.283519Z","time spent":"621.59726ms","remote":"127.0.0.1:55330","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-13T00:53:36.905118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.586783ms","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-13T00:53:36.905183Z","caller":"traceutil/trace.go:171","msg":"trace[145092016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"766.722116ms","start":"2026-08-13T00:53:36.138446Z","end":"2026-08-13T00:53:36.905168Z","steps":["trace[145092016] 'agreement among raft nodes before linearized reading' (duration: 766.512221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:36.905223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:36.138419Z","time spent":"766.795808ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T00:53:38.647951Z","caller":"traceutil/trace.go:171","msg":"trace[1915426935] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"646.63026ms","start":"2026-08-13T00:53:38.001293Z","end":"2026-08-13T00:53:38.647923Z","steps":["trace[1915426935] 'process raft request' (duration: 646.470796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:38.648113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:38.001267Z","time spent":"646.769894ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:38.648947Z","caller":"traceutil/trace.go:171","msg":"trace[626074381] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"647.294628ms","start":"2026-08-13T00:53:38.001643Z","end":"2026-08-13T00:53:38.648938Z","steps":["trace[626074381] 'process raft request' (duration: 647.069583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:38.649062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:38.001624Z","time spent":"647.401361ms","remote":"127.0.0.1:55594","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-13T00:53:38.649202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.411618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:53:38.649279Z","caller":"traceutil/trace.go:171","msg":"trace[1861143205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"366.515511ms","start":"2026-08-13T00:53:38.28274Z","end":"2026-08-13T00:53:38.649255Z","steps":["trace[1861143205] 'agreement among raft nodes before linearized reading' (duration: 366.402188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:38.649359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:38.282718Z","time spent":"366.628313ms","remote":"127.0.0.1:55320","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-13T00:53:38.64882Z","caller":"traceutil/trace.go:171","msg":"trace[1764376754] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"366.017667ms","start":"2026-08-13T00:53:38.28279Z","end":"2026-08-13T00:53:38.648808Z","steps":["trace[1764376754] 'read index received' (duration: 365.026351ms)","trace[1764376754] 'applied index is now lower than readState.Index' (duration: 990.676µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:53:42.126227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.681932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-08-13T00:53:42.126322Z","caller":"traceutil/trace.go:171","msg":"trace[884961406] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"125.830536ms","start":"2026-08-13T00:53:42.00047Z","end":"2026-08-13T00:53:42.126301Z","steps":["trace[884961406] 'range keys from in-memory index tree' (duration: 125.461097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:52.795581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.142184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:53:52.795654Z","caller":"traceutil/trace.go:171","msg":"trace[1017032467] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:764; }","duration":"680.271647ms","start":"2026-08-13T00:53:52.115369Z","end":"2026-08-13T00:53:52.795641Z","steps":["trace[1017032467] 'count revisions from in-memory index tree' (duration: 680.090583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:52.795684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:52.115354Z","time spent":"680.321949ms","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:53:52.7959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.130961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:53:52.795931Z","caller":"traceutil/trace.go:171","msg":"trace[1792796586] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:764; }","duration":"736.195793ms","start":"2026-08-13T00:53:52.059729Z","end":"2026-08-13T00:53:52.795924Z","steps":["trace[1792796586] 'count revisions from in-memory index tree' (duration: 736.010918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:52.795956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:52.059708Z","time spent":"736.241214ms","remote":"127.0.0.1:55808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:53:52.795956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.827601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5btts\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-13T00:53:52.796052Z","caller":"traceutil/trace.go:171","msg":"trace[1028576523] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5btts; range_end:; response_count:1; response_revision:764; }","duration":"864.974785ms","start":"2026-08-13T00:53:51.931053Z","end":"2026-08-13T00:53:52.796028Z","steps":["trace[1028576523] 'range keys from in-memory index tree' (duration: 864.620726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:53:52.796627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:53:51.931025Z","time spent":"865.582891ms","remote":"127.0.0.1:55508","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-5btts\" "} {"level":"info","ts":"2026-08-13T00:54:06.51482Z","caller":"traceutil/trace.go:171","msg":"trace[1393513108] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"233.190729ms","start":"2026-08-13T00:54:06.281608Z","end":"2026-08-13T00:54:06.514799Z","steps":["trace[1393513108] 'read index received' (duration: 203.825284ms)","trace[1393513108] 'applied index is now lower than readState.Index' (duration: 29.364305ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:06.514971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.348823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:06.515015Z","caller":"traceutil/trace.go:171","msg":"trace[1162197339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"233.434906ms","start":"2026-08-13T00:54:06.28157Z","end":"2026-08-13T00:54:06.515005Z","steps":["trace[1162197339] 'agreement among raft nodes before linearized reading' (duration: 233.348354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:06.5152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.371717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-jx9w7\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-08-13T00:54:06.515284Z","caller":"traceutil/trace.go:171","msg":"trace[1393580338] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-jx9w7; range_end:; response_count:1; response_revision:895; }","duration":"147.50809ms","start":"2026-08-13T00:54:06.36776Z","end":"2026-08-13T00:54:06.515268Z","steps":["trace[1393580338] 'agreement among raft nodes before linearized reading' (duration: 147.234933ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:06.863189Z","caller":"traceutil/trace.go:171","msg":"trace[1991290225] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"341.564141ms","start":"2026-08-13T00:54:06.521597Z","end":"2026-08-13T00:54:06.863161Z","steps":["trace[1991290225] 'process raft request' (duration: 341.361185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:06.863349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:06.521576Z","time spent":"341.683094ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:54:07.602193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.434536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:54:07.602297Z","caller":"traceutil/trace.go:171","msg":"trace[878639187] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"560.654883ms","start":"2026-08-13T00:54:07.041617Z","end":"2026-08-13T00:54:07.602272Z","steps":["trace[878639187] 'read index received' (duration: 28.501µs)","trace[878639187] 'applied index is now lower than readState.Index' (duration: 560.625142ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:54:07.602361Z","caller":"traceutil/trace.go:171","msg":"trace[320844458] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"665.427869ms","start":"2026-08-13T00:54:06.936902Z","end":"2026-08-13T00:54:07.60233Z","steps":["trace[320844458] 'process raft request' (duration: 96.774868ms)","trace[320844458] 'compare' (duration: 568.269822ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:07.602452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.832317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T00:54:07.602475Z","caller":"traceutil/trace.go:171","msg":"trace[797033342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"560.91203ms","start":"2026-08-13T00:54:07.041556Z","end":"2026-08-13T00:54:07.602468Z","steps":["trace[797033342] 'agreement among raft nodes before linearized reading' (duration: 560.801457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:07.602483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:06.936889Z","time spent":"665.527673ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:54:07.602503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.041539Z","time spent":"560.953351ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T00:54:07.602664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.392364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-13T00:54:07.602694Z","caller":"traceutil/trace.go:171","msg":"trace[2060592494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"558.431295ms","start":"2026-08-13T00:54:07.044251Z","end":"2026-08-13T00:54:07.602682Z","steps":["trace[2060592494] 'agreement among raft nodes before linearized reading' (duration: 558.343163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:07.602719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.044242Z","time spent":"558.471816ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T00:54:07.602804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.438984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:07.60284Z","caller":"traceutil/trace.go:171","msg":"trace[196368808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"319.500976ms","start":"2026-08-13T00:54:07.283329Z","end":"2026-08-13T00:54:07.60283Z","steps":["trace[196368808] 'agreement among raft nodes before linearized reading' (duration: 319.445925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:07.602869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.283314Z","time spent":"319.547967ms","remote":"127.0.0.1:55330","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-13T00:54:08.069479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.522474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:54:08.069552Z","caller":"traceutil/trace.go:171","msg":"trace[1493325690] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"463.248528ms","start":"2026-08-13T00:54:07.606292Z","end":"2026-08-13T00:54:08.069541Z","steps":["trace[1493325690] 'read index received' (duration: 97.61598ms)","trace[1493325690] 'applied index is now lower than readState.Index' (duration: 365.631588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:08.069635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.3411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:08.069657Z","caller":"traceutil/trace.go:171","msg":"trace[1148861394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"463.384001ms","start":"2026-08-13T00:54:07.606265Z","end":"2026-08-13T00:54:08.069649Z","steps":["trace[1148861394] 'agreement among raft nodes before linearized reading' (duration: 463.305999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:08.069679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.60625Z","time spent":"463.422712ms","remote":"127.0.0.1:55320","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-13T00:54:08.069613Z","caller":"traceutil/trace.go:171","msg":"trace[413859895] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"463.418563ms","start":"2026-08-13T00:54:07.606168Z","end":"2026-08-13T00:54:08.069586Z","steps":["trace[413859895] 'process raft request' (duration: 97.722494ms)","trace[413859895] 'compare' (duration: 365.328439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:08.069877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.606136Z","time spent":"463.624208ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:08.072789Z","caller":"traceutil/trace.go:171","msg":"trace[1959560726] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"465.24025ms","start":"2026-08-13T00:54:07.607522Z","end":"2026-08-13T00:54:08.072762Z","steps":["trace[1959560726] 'process raft request' (duration: 465.095656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:08.072959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:07.607505Z","time spent":"465.360492ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:08.078203Z","caller":"traceutil/trace.go:171","msg":"trace[2073783726] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"277.084463ms","start":"2026-08-13T00:54:07.801103Z","end":"2026-08-13T00:54:08.078188Z","steps":["trace[2073783726] 'process raft request' (duration: 276.98652ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:39.247346Z","caller":"traceutil/trace.go:171","msg":"trace[676875531] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"203.565256ms","start":"2026-08-13T00:54:39.043759Z","end":"2026-08-13T00:54:39.247324Z","steps":["trace[676875531] 'process raft request' (duration: 203.413162ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:39.495307Z","caller":"traceutil/trace.go:171","msg":"trace[23183722] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"450.714045ms","start":"2026-08-13T00:54:39.044566Z","end":"2026-08-13T00:54:39.49528Z","steps":["trace[23183722] 'process raft request' (duration: 450.565821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:39.495435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:39.044553Z","time spent":"450.821158ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:39.495397Z","caller":"traceutil/trace.go:171","msg":"trace[797915135] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1057; }","duration":"211.645864ms","start":"2026-08-13T00:54:39.283714Z","end":"2026-08-13T00:54:39.49536Z","steps":["trace[797915135] 'read index received' (duration: 211.632973ms)","trace[797915135] 'applied index is now lower than readState.Index' (duration: 9.981µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:39.495481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.775798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:39.495543Z","caller":"traceutil/trace.go:171","msg":"trace[1021769893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"211.904921ms","start":"2026-08-13T00:54:39.283622Z","end":"2026-08-13T00:54:39.495527Z","steps":["trace[1021769893] 'agreement among raft nodes before linearized reading' (duration: 211.819809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:40.70815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T00:54:41.130291Z","caller":"traceutil/trace.go:171","msg":"trace[304949627] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"922.471715ms","start":"2026-08-13T00:54:40.207795Z","end":"2026-08-13T00:54:41.130266Z","steps":["trace[304949627] 'read index received' (duration: 922.340272ms)","trace[304949627] 'applied index is now lower than readState.Index' (duration: 130.373µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:41.130502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.695573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T00:54:41.130565Z","caller":"traceutil/trace.go:171","msg":"trace[1550149015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"922.807796ms","start":"2026-08-13T00:54:40.207746Z","end":"2026-08-13T00:54:41.130554Z","steps":["trace[1550149015] 'agreement among raft nodes before linearized reading' (duration: 922.644321ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:41.130377Z","caller":"traceutil/trace.go:171","msg":"trace[14701579] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"951.376903ms","start":"2026-08-13T00:54:40.178983Z","end":"2026-08-13T00:54:41.13036Z","steps":["trace[14701579] 'process raft request' (duration: 951.116706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.130601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.207729Z","time spent":"922.862597ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T00:54:41.13063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.718532ms","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":"warn","ts":"2026-08-13T00:54:41.130643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.63456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-13T00:54:41.13068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.178959Z","time spent":"951.646991ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:41.130705Z","caller":"traceutil/trace.go:171","msg":"trace[625587972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"916.743593ms","start":"2026-08-13T00:54:40.213947Z","end":"2026-08-13T00:54:41.13069Z","steps":["trace[625587972] 'agreement among raft nodes before linearized reading' (duration: 916.549097ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:41.130685Z","caller":"traceutil/trace.go:171","msg":"trace[1658073104] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1018; }","duration":"661.789104ms","start":"2026-08-13T00:54:40.468883Z","end":"2026-08-13T00:54:41.130673Z","steps":["trace[1658073104] 'agreement among raft nodes before linearized reading' (duration: 661.684631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.13074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.213931Z","time spent":"916.802284ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T00:54:41.130772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.468822Z","time spent":"661.940238ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:54:41.130777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.155179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:54:41.130799Z","caller":"traceutil/trace.go:171","msg":"trace[1454245528] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1018; }","duration":"444.21398ms","start":"2026-08-13T00:54:40.68658Z","end":"2026-08-13T00:54:41.130794Z","steps":["trace[1454245528] 'agreement among raft nodes before linearized reading' (duration: 444.123998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.130821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.68656Z","time spent":"444.256812ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-08-13T00:54:41.130847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.347698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:41.130868Z","caller":"traceutil/trace.go:171","msg":"trace[211426046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"847.408309ms","start":"2026-08-13T00:54:40.283451Z","end":"2026-08-13T00:54:41.13086Z","steps":["trace[211426046] 'agreement among raft nodes before linearized reading' (duration: 847.372148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.130883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:40.283438Z","time spent":"847.442181ms","remote":"127.0.0.1:55330","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-13T00:54:41.949379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.684605ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-13T00:54:41.949487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.131698Z","time spent":"817.78572ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-13T00:54:41.988947Z","caller":"traceutil/trace.go:171","msg":"trace[349755438] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"853.626294ms","start":"2026-08-13T00:54:41.135302Z","end":"2026-08-13T00:54:41.988929Z","steps":["trace[349755438] 'process raft request' (duration: 853.580503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.989062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.135293Z","time spent":"853.708786ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:41.989178Z","caller":"traceutil/trace.go:171","msg":"trace[1196548141] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"737.410007ms","start":"2026-08-13T00:54:41.251762Z","end":"2026-08-13T00:54:41.989172Z","steps":["trace[1196548141] 'read index received' (duration: 456.915412ms)","trace[1196548141] 'applied index is now lower than readState.Index' (duration: 280.494115ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:54:41.989183Z","caller":"traceutil/trace.go:171","msg":"trace[1168688056] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"853.915321ms","start":"2026-08-13T00:54:41.135073Z","end":"2026-08-13T00:54:41.988988Z","steps":["trace[1168688056] 'process raft request' (duration: 853.683355ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:54:41.989277Z","caller":"traceutil/trace.go:171","msg":"trace[1873895491] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"320.326497ms","start":"2026-08-13T00:54:41.668899Z","end":"2026-08-13T00:54:41.989226Z","steps":["trace[1873895491] 'process raft request' (duration: 320.006488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.989336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.595081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T00:54:41.989365Z","caller":"traceutil/trace.go:171","msg":"trace[919159351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"737.668594ms","start":"2026-08-13T00:54:41.25169Z","end":"2026-08-13T00:54:41.989359Z","steps":["trace[919159351] 'agreement among raft nodes before linearized reading' (duration: 737.582702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:41.98939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.251663Z","time spent":"737.720765ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:54:41.989442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.668876Z","time spent":"320.486131ms","remote":"127.0.0.1:55594","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-13T00:54:41.989458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.135054Z","time spent":"854.293142ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:54:42.067507Z","caller":"traceutil/trace.go:171","msg":"trace[283421597] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"116.672789ms","start":"2026-08-13T00:54:41.950734Z","end":"2026-08-13T00:54:42.067407Z","steps":["trace[283421597] 'process raft request' (duration: 102.693593ms)","trace[283421597] 'compare' (duration: 13.814402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:42.067701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.529288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:54:42.067774Z","caller":"traceutil/trace.go:171","msg":"trace[1867978781] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1023; }","duration":"173.652191ms","start":"2026-08-13T00:54:41.894105Z","end":"2026-08-13T00:54:42.067757Z","steps":["trace[1867978781] 'agreement among raft nodes before linearized reading' (duration: 173.465886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:42.068009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.361162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:42.068133Z","caller":"traceutil/trace.go:171","msg":"trace[347893720] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1023; }","duration":"141.520527ms","start":"2026-08-13T00:54:41.926595Z","end":"2026-08-13T00:54:42.068115Z","steps":["trace[347893720] 'agreement among raft nodes before linearized reading' (duration: 141.352102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:42.068027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.792119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T00:54:42.068336Z","caller":"traceutil/trace.go:171","msg":"trace[1666010806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"302.143538ms","start":"2026-08-13T00:54:41.76617Z","end":"2026-08-13T00:54:42.068314Z","steps":["trace[1666010806] 'agreement among raft nodes before linearized reading' (duration: 301.687906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:42.068365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.48187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-13T00:54:42.068392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.766126Z","time spent":"302.24603ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T00:54:42.068482Z","caller":"traceutil/trace.go:171","msg":"trace[1560286792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"569.643184ms","start":"2026-08-13T00:54:41.498787Z","end":"2026-08-13T00:54:42.068431Z","steps":["trace[1560286792] 'agreement among raft nodes before linearized reading' (duration: 568.74142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:42.068581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:41.498773Z","time spent":"569.754257ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T00:54:42.281574Z","caller":"traceutil/trace.go:171","msg":"trace[293978891] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"207.243435ms","start":"2026-08-13T00:54:42.074295Z","end":"2026-08-13T00:54:42.281538Z","steps":["trace[293978891] 'process raft request' (duration: 121.90582ms)","trace[293978891] 'compare' (duration: 85.049027ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:54:42.281797Z","caller":"traceutil/trace.go:171","msg":"trace[1679831261] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"206.373522ms","start":"2026-08-13T00:54:42.075396Z","end":"2026-08-13T00:54:42.28177Z","steps":["trace[1679831261] 'read index received' (duration: 33.939393ms)","trace[1679831261] 'applied index is now lower than readState.Index' (duration: 172.432369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:54:42.282085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.67347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:54:42.282233Z","caller":"traceutil/trace.go:171","msg":"trace[2076702121] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1025; }","duration":"206.809374ms","start":"2026-08-13T00:54:42.075372Z","end":"2026-08-13T00:54:42.282181Z","steps":["trace[2076702121] 'agreement among raft nodes before linearized reading' (duration: 206.487605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:43.718578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502227,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T00:54:43.796007Z","caller":"traceutil/trace.go:171","msg":"trace[112633611] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"577.781943ms","start":"2026-08-13T00:54:43.218192Z","end":"2026-08-13T00:54:43.795974Z","steps":["trace[112633611] 'read index received' (duration: 577.510146ms)","trace[112633611] 'applied index is now lower than readState.Index' (duration: 270.617µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:54:43.796231Z","caller":"traceutil/trace.go:171","msg":"trace[25188461] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"659.692446ms","start":"2026-08-13T00:54:43.136438Z","end":"2026-08-13T00:54:43.796131Z","steps":["trace[25188461] 'process raft request' (duration: 659.354887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:43.796288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.0664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:54:43.79637Z","caller":"traceutil/trace.go:171","msg":"trace[1729418451] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1026; }","duration":"578.235535ms","start":"2026-08-13T00:54:43.218116Z","end":"2026-08-13T00:54:43.796351Z","steps":["trace[1729418451] 'agreement among raft nodes before linearized reading' (duration: 578.034959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:43.796428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:43.218094Z","time spent":"578.323328ms","remote":"127.0.0.1:55500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:54:43.796451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:43.136414Z","time spent":"659.919942ms","remote":"127.0.0.1:55594","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-13T00:54:43.796725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.795105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:54:43.797203Z","caller":"traceutil/trace.go:171","msg":"trace[721285716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"513.376071ms","start":"2026-08-13T00:54:43.283798Z","end":"2026-08-13T00:54:43.797175Z","steps":["trace[721285716] 'agreement among raft nodes before linearized reading' (duration: 512.774025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:54:43.797405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:54:43.283784Z","time spent":"513.605027ms","remote":"127.0.0.1:55330","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-13T00:54:44.25954Z","caller":"traceutil/trace.go:171","msg":"trace[142638584] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"101.145991ms","start":"2026-08-13T00:54:44.158367Z","end":"2026-08-13T00:54:44.259513Z","steps":["trace[142638584] 'process raft request' (duration: 45.969677ms)","trace[142638584] 'compare' (duration: 55.065061ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:54:45.343087Z","caller":"traceutil/trace.go:171","msg":"trace[929704180] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"201.83458ms","start":"2026-08-13T00:54:45.141205Z","end":"2026-08-13T00:54:45.34304Z","steps":["trace[929704180] 'process raft request' (duration: 201.535892ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:00.018457Z","caller":"traceutil/trace.go:171","msg":"trace[1906284149] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"108.367556ms","start":"2026-08-13T00:54:59.910065Z","end":"2026-08-13T00:55:00.018433Z","steps":["trace[1906284149] 'read index received' (duration: 108.128069ms)","trace[1906284149] 'applied index is now lower than readState.Index' (duration: 238.627µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:00.018553Z","caller":"traceutil/trace.go:171","msg":"trace[356438094] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"121.742226ms","start":"2026-08-13T00:54:59.896768Z","end":"2026-08-13T00:55:00.01851Z","steps":["trace[356438094] 'process raft request' (duration: 121.486379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:00.018665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.566332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:00.018849Z","caller":"traceutil/trace.go:171","msg":"trace[586563546] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1130; }","duration":"108.721555ms","start":"2026-08-13T00:54:59.910019Z","end":"2026-08-13T00:55:00.01874Z","steps":["trace[586563546] 'agreement among raft nodes before linearized reading' (duration: 108.52461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:00.726071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T00:55:01.178956Z","caller":"traceutil/trace.go:171","msg":"trace[1479850148] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"953.630643ms","start":"2026-08-13T00:55:00.225302Z","end":"2026-08-13T00:55:01.178933Z","steps":["trace[1479850148] 'read index received' (duration: 953.51529ms)","trace[1479850148] 'applied index is now lower than readState.Index' (duration: 114.273µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:01.179079Z","caller":"traceutil/trace.go:171","msg":"trace[607588340] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"956.043329ms","start":"2026-08-13T00:55:00.223023Z","end":"2026-08-13T00:55:01.179067Z","steps":["trace[607588340] 'process raft request' (duration: 955.753291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:01.17918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.223011Z","time spent":"956.09555ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:55:01.179422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.49607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-13T00:55:01.179435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.112206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T00:55:01.179491Z","caller":"traceutil/trace.go:171","msg":"trace[1184196844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"795.608033ms","start":"2026-08-13T00:55:00.383865Z","end":"2026-08-13T00:55:01.179473Z","steps":["trace[1184196844] 'agreement among raft nodes before linearized reading' (duration: 795.429008ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:01.179497Z","caller":"traceutil/trace.go:171","msg":"trace[508563837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"954.23126ms","start":"2026-08-13T00:55:00.225252Z","end":"2026-08-13T00:55:01.179484Z","steps":["trace[508563837] 'agreement among raft nodes before linearized reading' (duration: 954.043575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:01.17953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.22523Z","time spent":"954.294601ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T00:55:01.179528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.383846Z","time spent":"795.673525ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:55:01.179663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.418626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T00:55:01.179694Z","caller":"traceutil/trace.go:171","msg":"trace[968639979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1131; }","duration":"850.474938ms","start":"2026-08-13T00:55:00.329211Z","end":"2026-08-13T00:55:01.179686Z","steps":["trace[968639979] 'agreement among raft nodes before linearized reading' (duration: 850.396096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:01.179717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.329196Z","time spent":"850.51444ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T00:55:01.179696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.222893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-13T00:55:01.179732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.316637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:55:01.179782Z","caller":"traceutil/trace.go:171","msg":"trace[1892215832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1131; }","duration":"953.549261ms","start":"2026-08-13T00:55:00.226213Z","end":"2026-08-13T00:55:01.179762Z","steps":["trace[1892215832] 'agreement among raft nodes before linearized reading' (duration: 952.999427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:01.179793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.489507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:01.179793Z","caller":"traceutil/trace.go:171","msg":"trace[1366700128] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1131; }","duration":"491.374529ms","start":"2026-08-13T00:55:00.6884Z","end":"2026-08-13T00:55:01.179774Z","steps":["trace[1366700128] 'agreement among raft nodes before linearized reading' (duration: 491.201014ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:01.179816Z","caller":"traceutil/trace.go:171","msg":"trace[1986571700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1131; }","duration":"896.535779ms","start":"2026-08-13T00:55:00.283274Z","end":"2026-08-13T00:55:01.17981Z","steps":["trace[1986571700] 'agreement among raft nodes before linearized reading' (duration: 896.503438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:01.179831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.283261Z","time spent":"896.567429ms","remote":"127.0.0.1:55330","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-13T00:55:01.179838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.688303Z","time spent":"491.524862ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-08-13T00:55:01.179841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:00.226204Z","time spent":"953.626143ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T00:55:02.791442Z","caller":"traceutil/trace.go:171","msg":"trace[1094432167] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"768.037172ms","start":"2026-08-13T00:55:02.023375Z","end":"2026-08-13T00:55:02.791412Z","steps":["trace[1094432167] 'process raft request' (duration: 739.480484ms)","trace[1094432167] 'compare' (duration: 28.242879ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:55:02.791587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:02.023352Z","time spent":"768.157834ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:02.791987Z","caller":"traceutil/trace.go:171","msg":"trace[1979189008] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1184; }","duration":"508.211632ms","start":"2026-08-13T00:55:02.283763Z","end":"2026-08-13T00:55:02.791974Z","steps":["trace[1979189008] 'read index received' (duration: 479.205382ms)","trace[1979189008] 'applied index is now lower than readState.Index' (duration: 29.00517ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:02.792068Z","caller":"traceutil/trace.go:171","msg":"trace[149635493] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"519.570127ms","start":"2026-08-13T00:55:02.272489Z","end":"2026-08-13T00:55:02.79206Z","steps":["trace[149635493] 'process raft request' (duration: 518.834797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:02.79213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:02.272466Z","time spent":"519.628858ms","remote":"127.0.0.1:55594","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-13T00:55:02.792249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.461258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:02.792335Z","caller":"traceutil/trace.go:171","msg":"trace[1658399164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"508.586182ms","start":"2026-08-13T00:55:02.283725Z","end":"2026-08-13T00:55:02.792311Z","steps":["trace[1658399164] 'agreement among raft nodes before linearized reading' (duration: 508.442458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:02.793094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.888924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:55:02.793144Z","caller":"traceutil/trace.go:171","msg":"trace[208285193] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1138; }","duration":"287.975446ms","start":"2026-08-13T00:55:02.505153Z","end":"2026-08-13T00:55:02.793129Z","steps":["trace[208285193] 'agreement among raft nodes before linearized reading' (duration: 286.948629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:02.793495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:02.283709Z","time spent":"509.227358ms","remote":"127.0.0.1:55330","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-13T00:55:03.353269Z","caller":"traceutil/trace.go:171","msg":"trace[1405677011] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"136.254695ms","start":"2026-08-13T00:55:03.216984Z","end":"2026-08-13T00:55:03.353239Z","steps":["trace[1405677011] 'process raft request' (duration: 136.07149ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:03.359788Z","caller":"traceutil/trace.go:171","msg":"trace[1902089701] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"142.687709ms","start":"2026-08-13T00:55:03.217084Z","end":"2026-08-13T00:55:03.359772Z","steps":["trace[1902089701] 'process raft request' (duration: 142.662728ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:03.359818Z","caller":"traceutil/trace.go:171","msg":"trace[97802836] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"142.797671ms","start":"2026-08-13T00:55:03.217005Z","end":"2026-08-13T00:55:03.359802Z","steps":["trace[97802836] 'process raft request' (duration: 142.652477ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:03.359863Z","caller":"traceutil/trace.go:171","msg":"trace[842914236] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"142.789901ms","start":"2026-08-13T00:55:03.217052Z","end":"2026-08-13T00:55:03.359842Z","steps":["trace[842914236] 'process raft request' (duration: 142.676608ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:27.684131Z","caller":"traceutil/trace.go:171","msg":"trace[881914032] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"149.105267ms","start":"2026-08-13T00:55:27.535001Z","end":"2026-08-13T00:55:27.684106Z","steps":["trace[881914032] 'process raft request' (duration: 149.040965ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:27.68418Z","caller":"traceutil/trace.go:171","msg":"trace[1886298502] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"156.709856ms","start":"2026-08-13T00:55:27.527439Z","end":"2026-08-13T00:55:27.684149Z","steps":["trace[1886298502] 'process raft request' (duration: 156.47556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.180689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.701704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T00:55:29.180762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.768379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:29.180791Z","caller":"traceutil/trace.go:171","msg":"trace[654665463] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1267; }","duration":"716.851598ms","start":"2026-08-13T00:55:28.463916Z","end":"2026-08-13T00:55:29.180768Z","steps":["trace[654665463] 'count revisions from in-memory index tree' (duration: 716.625091ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:29.180832Z","caller":"traceutil/trace.go:171","msg":"trace[2044298489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"897.876212ms","start":"2026-08-13T00:55:28.282941Z","end":"2026-08-13T00:55:29.180817Z","steps":["trace[2044298489] 'range keys from in-memory index tree' (duration: 897.697028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.180857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:28.463897Z","time spent":"716.94093ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:55:29.180884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183771692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:55:29.180894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:28.282921Z","time spent":"897.955185ms","remote":"127.0.0.1:55330","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-13T00:55:29.18094Z","caller":"traceutil/trace.go:171","msg":"trace[126942931] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1267; }","duration":"1.183840933s","start":"2026-08-13T00:55:27.997081Z","end":"2026-08-13T00:55:29.180922Z","steps":["trace[126942931] 'range keys from in-memory index tree' (duration: 1.183620487s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.180988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:27.99707Z","time spent":"1.183907525s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-13T00:55:29.181203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136048168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:55:29.181241Z","caller":"traceutil/trace.go:171","msg":"trace[976027438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1267; }","duration":"1.136113s","start":"2026-08-13T00:55:28.045118Z","end":"2026-08-13T00:55:29.181231Z","steps":["trace[976027438] 'count revisions from in-memory index tree' (duration: 1.135982566s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.181277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:28.045101Z","time spent":"1.136167041s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:55:29.180814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187401547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:29.181679Z","caller":"traceutil/trace.go:171","msg":"trace[1960822971] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1267; }","duration":"1.1883269s","start":"2026-08-13T00:55:27.993331Z","end":"2026-08-13T00:55:29.181658Z","steps":["trace[1960822971] 'count revisions from in-memory index tree' (duration: 1.187335474s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.181745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:27.993314Z","time spent":"1.188409623s","remote":"127.0.0.1:55644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:55:29.182656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.408746ms","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-13T00:55:29.182771Z","caller":"traceutil/trace.go:171","msg":"trace[2075222577] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1267; }","duration":"518.525989ms","start":"2026-08-13T00:55:28.66423Z","end":"2026-08-13T00:55:29.182756Z","steps":["trace[2075222577] 'range keys from in-memory index tree' (duration: 518.395116ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:29.319454Z","caller":"traceutil/trace.go:171","msg":"trace[105543523] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"136.495734ms","start":"2026-08-13T00:55:29.182939Z","end":"2026-08-13T00:55:29.319435Z","steps":["trace[105543523] 'read index received' (duration: 136.263028ms)","trace[105543523] 'applied index is now lower than readState.Index' (duration: 232.006µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:55:29.319648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.71424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:55:29.319854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.598943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:29.319928Z","caller":"traceutil/trace.go:171","msg":"trace[47411005] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1268; }","duration":"132.717777ms","start":"2026-08-13T00:55:29.187199Z","end":"2026-08-13T00:55:29.319917Z","steps":["trace[47411005] 'agreement among raft nodes before linearized reading' (duration: 132.531661ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:29.319519Z","caller":"traceutil/trace.go:171","msg":"trace[3638790] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"397.223192ms","start":"2026-08-13T00:55:28.922267Z","end":"2026-08-13T00:55:29.319491Z","steps":["trace[3638790] 'process raft request' (duration: 397.012626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.32022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:28.922247Z","time spent":"397.836019ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:29.319848Z","caller":"traceutil/trace.go:171","msg":"trace[1282096405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"136.952016ms","start":"2026-08-13T00:55:29.18288Z","end":"2026-08-13T00:55:29.319832Z","steps":["trace[1282096405] 'agreement among raft nodes before linearized reading' (duration: 136.714329ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:29.485508Z","caller":"traceutil/trace.go:171","msg":"trace[1516450685] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"163.531486ms","start":"2026-08-13T00:55:29.321949Z","end":"2026-08-13T00:55:29.485481Z","steps":["trace[1516450685] 'process raft request' (duration: 103.698844ms)","trace[1516450685] 'compare' (duration: 59.671678ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:29.485703Z","caller":"traceutil/trace.go:171","msg":"trace[959408279] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"156.794548ms","start":"2026-08-13T00:55:29.328877Z","end":"2026-08-13T00:55:29.485672Z","steps":["trace[959408279] 'read index received' (duration: 96.709609ms)","trace[959408279] 'applied index is now lower than readState.Index' (duration: 60.080979ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:55:29.485962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.051285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:29.486084Z","caller":"traceutil/trace.go:171","msg":"trace[451068889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"157.219609ms","start":"2026-08-13T00:55:29.328835Z","end":"2026-08-13T00:55:29.486055Z","steps":["trace[451068889] 'agreement among raft nodes before linearized reading' (duration: 156.971542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.486242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.946739ms","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-13T00:55:29.486371Z","caller":"traceutil/trace.go:171","msg":"trace[1384549528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"155.118714ms","start":"2026-08-13T00:55:29.331238Z","end":"2026-08-13T00:55:29.486357Z","steps":["trace[1384549528] 'agreement among raft nodes before linearized reading' (duration: 154.717103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:29.956426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.458581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:55:29.9566Z","caller":"traceutil/trace.go:171","msg":"trace[72921019] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"140.948562ms","start":"2026-08-13T00:55:29.815632Z","end":"2026-08-13T00:55:29.956581Z","steps":["trace[72921019] 'process raft request' (duration: 140.888981ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:29.956692Z","caller":"traceutil/trace.go:171","msg":"trace[170257290] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"141.533618ms","start":"2026-08-13T00:55:29.815076Z","end":"2026-08-13T00:55:29.95661Z","steps":["trace[170257290] 'process raft request' (duration: 14.79962ms)","trace[170257290] 'compare' (duration: 126.317087ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:30.886094Z","caller":"traceutil/trace.go:171","msg":"trace[2127074447] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"196.471371ms","start":"2026-08-13T00:55:30.689607Z","end":"2026-08-13T00:55:30.886079Z","steps":["trace[2127074447] 'read index received' (duration: 196.311236ms)","trace[2127074447] 'applied index is now lower than readState.Index' (duration: 159.275µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:30.886174Z","caller":"traceutil/trace.go:171","msg":"trace[36788244] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"245.627362ms","start":"2026-08-13T00:55:30.640494Z","end":"2026-08-13T00:55:30.886121Z","steps":["trace[36788244] 'process raft request' (duration: 245.467868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:30.886377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.757569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:55:30.886455Z","caller":"traceutil/trace.go:171","msg":"trace[9785489] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1286; }","duration":"196.880612ms","start":"2026-08-13T00:55:30.689557Z","end":"2026-08-13T00:55:30.886437Z","steps":["trace[9785489] 'agreement among raft nodes before linearized reading' (duration: 196.586564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:30.887115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.925346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:30.887197Z","caller":"traceutil/trace.go:171","msg":"trace[186097182] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1286; }","duration":"102.08145ms","start":"2026-08-13T00:55:30.785099Z","end":"2026-08-13T00:55:30.88718Z","steps":["trace[186097182] 'agreement among raft nodes before linearized reading' (duration: 101.142996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.086522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:55:32.587099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:55:32.743998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.268000733s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T00:55:32.74443Z","caller":"traceutil/trace.go:171","msg":"trace[1079144933] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"1.158530918s","start":"2026-08-13T00:55:31.585875Z","end":"2026-08-13T00:55:32.744406Z","steps":["trace[1079144933] 'read index received' (duration: 1.158258151s)","trace[1079144933] 'applied index is now lower than readState.Index' (duration: 271.967µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:32.744507Z","caller":"traceutil/trace.go:171","msg":"trace[836727702] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"1.268584498s","start":"2026-08-13T00:55:31.475887Z","end":"2026-08-13T00:55:32.744471Z","steps":["trace[836727702] 'process raft request' (duration: 1.268380883s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.744692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.628283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-08-13T00:55:32.744734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.126512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:32.744762Z","caller":"traceutil/trace.go:171","msg":"trace[914460557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"461.193133ms","start":"2026-08-13T00:55:32.283562Z","end":"2026-08-13T00:55:32.744755Z","steps":["trace[914460557] 'agreement among raft nodes before linearized reading' (duration: 461.160492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.744785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.283539Z","time spent":"461.238994ms","remote":"127.0.0.1:55330","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-13T00:55:32.744769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119676308s","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-13T00:55:32.744803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024336143s","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-13T00:55:32.744841Z","caller":"traceutil/trace.go:171","msg":"trace[164275228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"1.024402485s","start":"2026-08-13T00:55:31.720431Z","end":"2026-08-13T00:55:32.744833Z","steps":["trace[164275228] 'agreement among raft nodes before linearized reading' (duration: 1.024324323s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.744821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113367281s","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-13T00:55:32.744848Z","caller":"traceutil/trace.go:171","msg":"trace[53680799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"1.11979835s","start":"2026-08-13T00:55:31.625032Z","end":"2026-08-13T00:55:32.74483Z","steps":["trace[53680799] 'agreement among raft nodes before linearized reading' (duration: 1.119528043s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.744868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.720415Z","time spent":"1.024446776s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T00:55:32.744735Z","caller":"traceutil/trace.go:171","msg":"trace[126331155] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1291; }","duration":"737.726186ms","start":"2026-08-13T00:55:32.006999Z","end":"2026-08-13T00:55:32.744725Z","steps":["trace[126331155] 'agreement among raft nodes before linearized reading' (duration: 737.611833ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:32.74488Z","caller":"traceutil/trace.go:171","msg":"trace[639677729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"1.113463743s","start":"2026-08-13T00:55:31.631405Z","end":"2026-08-13T00:55:32.744869Z","steps":["trace[639677729] 'agreement among raft nodes before linearized reading' (duration: 1.113272948s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.744895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.625016Z","time spent":"1.119870093s","remote":"127.0.0.1:55594","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-13T00:55:32.74489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112870018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-13T00:55:32.74492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.631387Z","time spent":"1.113525405s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:55:32.744905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.00698Z","time spent":"737.91861ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-08-13T00:55:32.744965Z","caller":"traceutil/trace.go:171","msg":"trace[1386330414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"1.112981971s","start":"2026-08-13T00:55:31.631966Z","end":"2026-08-13T00:55:32.744948Z","steps":["trace[1386330414] 'agreement among raft nodes before linearized reading' (duration: 1.112700414s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.7447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.054393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:55:32.745021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.631956Z","time spent":"1.113055133s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T00:55:32.745035Z","caller":"traceutil/trace.go:171","msg":"trace[1852670036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1291; }","duration":"302.416783ms","start":"2026-08-13T00:55:32.442597Z","end":"2026-08-13T00:55:32.745014Z","steps":["trace[1852670036] 'agreement among raft nodes before linearized reading' (duration: 302.069274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.745056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.475873Z","time spent":"1.268717421s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:55:32.745101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.442573Z","time spent":"302.505065ms","remote":"127.0.0.1:40254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:55:32.745154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159285707s","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-13T00:55:32.745796Z","caller":"traceutil/trace.go:171","msg":"trace[329440219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"1.159958555s","start":"2026-08-13T00:55:31.585823Z","end":"2026-08-13T00:55:32.745781Z","steps":["trace[329440219] 'agreement among raft nodes before linearized reading' (duration: 1.158681752s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:32.74585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:31.585803Z","time spent":"1.160032466s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:55:33.280538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:55:33.781637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:55:34.282847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369671352502803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:55:34.331161Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.579262847s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T00:55:34.341098Z","caller":"traceutil/trace.go:171","msg":"trace[1128244203] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.589347602s","start":"2026-08-13T00:55:32.751722Z","end":"2026-08-13T00:55:34.34107Z","steps":["trace[1128244203] 'process raft request' (duration: 1.579644056s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.341444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.751669Z","time spent":"1.589693751s","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.349798Z","caller":"traceutil/trace.go:171","msg":"trace[328966075] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.570048414s","start":"2026-08-13T00:55:32.779708Z","end":"2026-08-13T00:55:34.349756Z","steps":["trace[328966075] 'process raft request' (duration: 1.56988971s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.349976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779694Z","time spent":"1.570207399s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.350166Z","caller":"traceutil/trace.go:171","msg":"trace[760754819] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.570390723s","start":"2026-08-13T00:55:32.779753Z","end":"2026-08-13T00:55:34.350143Z","steps":["trace[760754819] 'process raft request' (duration: 1.569949252s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.350257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779739Z","time spent":"1.570487465s","remote":"127.0.0.1:55808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.351841Z","caller":"traceutil/trace.go:171","msg":"trace[1204944821] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1345; }","duration":"1.572094328s","start":"2026-08-13T00:55:32.779706Z","end":"2026-08-13T00:55:34.3518Z","steps":["trace[1204944821] 'read index received' (duration: 1.551684051s)","trace[1204944821] 'applied index is now lower than readState.Index' (duration: 20.329565ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:34.35201Z","caller":"traceutil/trace.go:171","msg":"trace[1165421707] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.572183861s","start":"2026-08-13T00:55:32.779808Z","end":"2026-08-13T00:55:34.351992Z","steps":["trace[1165421707] 'process raft request' (duration: 1.570365433s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.352119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779804Z","time spent":"1.572246862s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.35227Z","caller":"traceutil/trace.go:171","msg":"trace[985937157] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.572408116s","start":"2026-08-13T00:55:32.779851Z","end":"2026-08-13T00:55:34.352259Z","steps":["trace[985937157] 'process raft request' (duration: 1.570354692s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.352336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779847Z","time spent":"1.572458718s","remote":"127.0.0.1:55594","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-13T00:55:34.35246Z","caller":"traceutil/trace.go:171","msg":"trace[1714570636] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.571789161s","start":"2026-08-13T00:55:32.780656Z","end":"2026-08-13T00:55:34.352445Z","steps":["trace[1714570636] 'process raft request' (duration: 1.569581222s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.352526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.78064Z","time spent":"1.571851842s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.352636Z","caller":"traceutil/trace.go:171","msg":"trace[214091110] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.570577678s","start":"2026-08-13T00:55:32.779768Z","end":"2026-08-13T00:55:34.350346Z","steps":["trace[214091110] 'process raft request' (duration: 1.570338252s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.352728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779758Z","time spent":"1.572917359s","remote":"127.0.0.1:55594","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-13T00:55:34.352941Z","caller":"traceutil/trace.go:171","msg":"trace[1781329981] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"1.570928978s","start":"2026-08-13T00:55:32.781925Z","end":"2026-08-13T00:55:34.352854Z","steps":["trace[1781329981] 'process raft request' (duration: 1.56833965s)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:34.352988Z","caller":"traceutil/trace.go:171","msg":"trace[946353169] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"991.067139ms","start":"2026-08-13T00:55:33.361907Z","end":"2026-08-13T00:55:34.352974Z","steps":["trace[946353169] 'process raft request' (duration: 988.4182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.352998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.781919Z","time spent":"1.571053001s","remote":"127.0.0.1:55594","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-13T00:55:34.353042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:33.361847Z","time spent":"991.172492ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:34.353295Z","caller":"traceutil/trace.go:171","msg":"trace[1556898073] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"1.231158994s","start":"2026-08-13T00:55:33.122119Z","end":"2026-08-13T00:55:34.353278Z","steps":["trace[1556898073] 'process raft request' (duration: 1.228172266s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.353797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574012927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-zqd8r\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-08-13T00:55:34.357129Z","caller":"traceutil/trace.go:171","msg":"trace[1688225653] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-zqd8r; range_end:; response_count:1; response_revision:1302; }","duration":"1.577431478s","start":"2026-08-13T00:55:32.779676Z","end":"2026-08-13T00:55:34.357108Z","steps":["trace[1688225653] 'agreement among raft nodes before linearized reading' (duration: 1.573866584s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.357229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779662Z","time spent":"1.577540921s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":13747,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-zqd8r\" "} {"level":"warn","ts":"2026-08-13T00:55:34.353958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:33.122095Z","time spent":"1.231256067s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:55:34.354023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069781137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:55:34.358127Z","caller":"traceutil/trace.go:171","msg":"trace[1187663591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"1.073861163s","start":"2026-08-13T00:55:33.284214Z","end":"2026-08-13T00:55:34.358075Z","steps":["trace[1187663591] 'agreement among raft nodes before linearized reading' (duration: 1.069786917s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.358522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:33.2842Z","time spent":"1.074297525s","remote":"127.0.0.1:55330","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-13T00:55:34.358669Z","caller":"traceutil/trace.go:171","msg":"trace[214634732] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.578887306s","start":"2026-08-13T00:55:32.779761Z","end":"2026-08-13T00:55:34.358648Z","steps":["trace[214634732] 'process raft request' (duration: 1.570241279s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:34.358729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:32.779742Z","time spent":"1.578959037s","remote":"127.0.0.1:55500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:55:52.656275Z","caller":"traceutil/trace.go:171","msg":"trace[1490347612] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"149.323682ms","start":"2026-08-13T00:55:52.506923Z","end":"2026-08-13T00:55:52.656247Z","steps":["trace[1490347612] 'process raft request' (duration: 57.90086ms)","trace[1490347612] 'compare' (duration: 91.288998ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:52.659509Z","caller":"traceutil/trace.go:171","msg":"trace[289191117] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"151.975721ms","start":"2026-08-13T00:55:52.507518Z","end":"2026-08-13T00:55:52.659494Z","steps":["trace[289191117] 'process raft request' (duration: 151.859588ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:52.659841Z","caller":"traceutil/trace.go:171","msg":"trace[661538961] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"152.274468ms","start":"2026-08-13T00:55:52.507551Z","end":"2026-08-13T00:55:52.659825Z","steps":["trace[661538961] 'process raft request' (duration: 151.907939ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:52.659891Z","caller":"traceutil/trace.go:171","msg":"trace[26459605] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1439; }","duration":"119.396255ms","start":"2026-08-13T00:55:52.540471Z","end":"2026-08-13T00:55:52.659867Z","steps":["trace[26459605] 'read index received' (duration: 24.426731ms)","trace[26459605] 'applied index is now lower than readState.Index' (duration: 94.968624ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:52.6599Z","caller":"traceutil/trace.go:171","msg":"trace[1326011484] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"152.2976ms","start":"2026-08-13T00:55:52.507582Z","end":"2026-08-13T00:55:52.65988Z","steps":["trace[1326011484] 'process raft request' (duration: 152.127755ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:52.659938Z","caller":"traceutil/trace.go:171","msg":"trace[1618095784] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"151.492999ms","start":"2026-08-13T00:55:52.50842Z","end":"2026-08-13T00:55:52.659913Z","steps":["trace[1618095784] 'process raft request' (duration: 151.396366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:52.660147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.660512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T00:55:52.660285Z","caller":"traceutil/trace.go:171","msg":"trace[810270654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"119.842998ms","start":"2026-08-13T00:55:52.540431Z","end":"2026-08-13T00:55:52.660274Z","steps":["trace[810270654] 'agreement among raft nodes before linearized reading' (duration: 119.584911ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:21.030323Z","caller":"traceutil/trace.go:171","msg":"trace[1936015635] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"130.188968ms","start":"2026-08-13T00:56:20.900088Z","end":"2026-08-13T00:56:21.030277Z","steps":["trace[1936015635] 'process raft request' (duration: 101.28914ms)","trace[1936015635] 'compare' (duration: 28.703383ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:21.030913Z","caller":"traceutil/trace.go:171","msg":"trace[956747563] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"130.494147ms","start":"2026-08-13T00:56:20.90036Z","end":"2026-08-13T00:56:21.030854Z","steps":["trace[956747563] 'process raft request' (duration: 130.22386ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:21.031234Z","caller":"traceutil/trace.go:171","msg":"trace[547047000] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"128.977857ms","start":"2026-08-13T00:56:20.902241Z","end":"2026-08-13T00:56:21.031219Z","steps":["trace[547047000] 'process raft request' (duration: 128.915415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:27.197559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.09543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:56:27.197671Z","caller":"traceutil/trace.go:171","msg":"trace[1443113828] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1605; }","duration":"222.759968ms","start":"2026-08-13T00:56:26.974895Z","end":"2026-08-13T00:56:27.197655Z","steps":["trace[1443113828] 'read index received' (duration: 81.423352ms)","trace[1443113828] 'applied index is now lower than readState.Index' (duration: 141.335866ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:27.197872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.971713ms","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-13T00:56:27.197913Z","caller":"traceutil/trace.go:171","msg":"trace[1376306283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1541; }","duration":"223.043585ms","start":"2026-08-13T00:56:26.974859Z","end":"2026-08-13T00:56:27.197903Z","steps":["trace[1376306283] 'agreement among raft nodes before linearized reading' (duration: 222.870261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:27.197928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.065136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-13T00:56:27.197961Z","caller":"traceutil/trace.go:171","msg":"trace[266284825] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1541; }","duration":"142.121467ms","start":"2026-08-13T00:56:27.055829Z","end":"2026-08-13T00:56:27.197951Z","steps":["trace[266284825] 'agreement among raft nodes before linearized reading' (duration: 142.021905ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:27.19801Z","caller":"traceutil/trace.go:171","msg":"trace[1826922231] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"246.434409ms","start":"2026-08-13T00:56:26.95152Z","end":"2026-08-13T00:56:27.197955Z","steps":["trace[1826922231] 'process raft request' (duration: 104.849397ms)","trace[1826922231] 'compare' (duration: 140.878344ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:27.198007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.710995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T00:56:27.198121Z","caller":"traceutil/trace.go:171","msg":"trace[1677043187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1541; }","duration":"144.874099ms","start":"2026-08-13T00:56:27.053231Z","end":"2026-08-13T00:56:27.198105Z","steps":["trace[1677043187] 'agreement among raft nodes before linearized reading' (duration: 144.623103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:27.198046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.724402ms","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-13T00:56:27.19868Z","caller":"traceutil/trace.go:171","msg":"trace[1154222692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1541; }","duration":"143.41065ms","start":"2026-08-13T00:56:27.055253Z","end":"2026-08-13T00:56:27.198664Z","steps":["trace[1154222692] 'agreement among raft nodes before linearized reading' (duration: 142.682661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:00.952936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.90152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:57:00.953093Z","caller":"traceutil/trace.go:171","msg":"trace[551056490] linearizableReadLoop","detail":"{readStateIndex:1767; appliedIndex:1766; }","duration":"188.454245ms","start":"2026-08-13T00:57:00.764615Z","end":"2026-08-13T00:57:00.953069Z","steps":["trace[551056490] 'read index received' (duration: 62.257967ms)","trace[551056490] 'applied index is now lower than readState.Index' (duration: 126.194708ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:00.953134Z","caller":"traceutil/trace.go:171","msg":"trace[884350953] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"189.307947ms","start":"2026-08-13T00:57:00.763787Z","end":"2026-08-13T00:57:00.953095Z","steps":["trace[884350953] 'process raft request' (duration: 63.148809ms)","trace[884350953] 'compare' (duration: 125.764477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:57:00.953221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.000395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:57:00.953223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.601609ms","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-13T00:57:00.953267Z","caller":"traceutil/trace.go:171","msg":"trace[1779247729] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1695; }","duration":"177.050556ms","start":"2026-08-13T00:57:00.776203Z","end":"2026-08-13T00:57:00.953253Z","steps":["trace[1779247729] 'agreement among raft nodes before linearized reading' (duration: 176.973364ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:00.953292Z","caller":"traceutil/trace.go:171","msg":"trace[69370823] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1695; }","duration":"188.701762ms","start":"2026-08-13T00:57:00.764573Z","end":"2026-08-13T00:57:00.953275Z","steps":["trace[69370823] 'agreement among raft nodes before linearized reading' (duration: 188.571768ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:01.381518Z","caller":"traceutil/trace.go:171","msg":"trace[1350913405] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"105.77063ms","start":"2026-08-13T00:57:01.275728Z","end":"2026-08-13T00:57:01.381499Z","steps":["trace[1350913405] 'process raft request' (duration: 105.432012ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:01.543203Z","caller":"traceutil/trace.go:171","msg":"trace[1210356613] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"157.180673ms","start":"2026-08-13T00:57:01.386001Z","end":"2026-08-13T00:57:01.543181Z","steps":["trace[1210356613] 'process raft request' (duration: 97.708019ms)","trace[1210356613] 'compare' (duration: 59.33654ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:01.543475Z","caller":"traceutil/trace.go:171","msg":"trace[1152667448] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"157.179383ms","start":"2026-08-13T00:57:01.386276Z","end":"2026-08-13T00:57:01.543455Z","steps":["trace[1152667448] 'process raft request' (duration: 156.875095ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:01.543715Z","caller":"traceutil/trace.go:171","msg":"trace[2086481821] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"155.158829ms","start":"2026-08-13T00:57:01.388544Z","end":"2026-08-13T00:57:01.543703Z","steps":["trace[2086481821] 'process raft request' (duration: 155.075697ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:01.543834Z","caller":"traceutil/trace.go:171","msg":"trace[550165977] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"155.267472ms","start":"2026-08-13T00:57:01.388441Z","end":"2026-08-13T00:57:01.543708Z","steps":["trace[550165977] 'process raft request' (duration: 154.917593ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:10.099855Z","caller":"traceutil/trace.go:171","msg":"trace[1101963830] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"107.266251ms","start":"2026-08-13T00:58:09.992564Z","end":"2026-08-13T00:58:10.09983Z","steps":["trace[1101963830] 'process raft request' (duration: 69.417882ms)","trace[1101963830] 'compare' (duration: 37.458357ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:58:10.556193Z","caller":"traceutil/trace.go:171","msg":"trace[1632663071] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"226.330068ms","start":"2026-08-13T00:58:10.329835Z","end":"2026-08-13T00:58:10.556165Z","steps":["trace[1632663071] 'process raft request' (duration: 226.196625ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:32.649347Z","caller":"traceutil/trace.go:171","msg":"trace[1915282290] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"443.51746ms","start":"2026-08-13T00:58:32.205812Z","end":"2026-08-13T00:58:32.64933Z","steps":["trace[1915282290] 'process raft request' (duration: 443.416237ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:32.649362Z","caller":"traceutil/trace.go:171","msg":"trace[1728871480] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"443.669394ms","start":"2026-08-13T00:58:32.205656Z","end":"2026-08-13T00:58:32.649325Z","steps":["trace[1728871480] 'process raft request' (duration: 443.445928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:58:32.649495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:58:32.205806Z","time spent":"443.615392ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:58:32.64954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:58:32.205643Z","time spent":"443.803677ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:58:32.649615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.332558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:58:32.649714Z","caller":"traceutil/trace.go:171","msg":"trace[691775433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2140; }","duration":"365.470752ms","start":"2026-08-13T00:58:32.28422Z","end":"2026-08-13T00:58:32.649691Z","steps":["trace[691775433] 'agreement among raft nodes before linearized reading' (duration: 365.294358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:58:32.649769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:58:32.2842Z","time spent":"365.556735ms","remote":"127.0.0.1:55330","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-13T00:58:32.649287Z","caller":"traceutil/trace.go:171","msg":"trace[1271801449] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2229; }","duration":"365.00503ms","start":"2026-08-13T00:58:32.284261Z","end":"2026-08-13T00:58:32.649266Z","steps":["trace[1271801449] 'read index received' (duration: 364.774914ms)","trace[1271801449] 'applied index is now lower than readState.Index' (duration: 227.466µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:58:34.776599Z","caller":"traceutil/trace.go:171","msg":"trace[789754357] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"118.720403ms","start":"2026-08-13T00:58:34.657854Z","end":"2026-08-13T00:58:34.776574Z","steps":["trace[789754357] 'process raft request' (duration: 118.573999ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:34.839226Z","caller":"traceutil/trace.go:171","msg":"trace[774239599] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"181.010797ms","start":"2026-08-13T00:58:34.658188Z","end":"2026-08-13T00:58:34.839199Z","steps":["trace[774239599] 'process raft request' (duration: 178.207003ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:38.742556Z","caller":"traceutil/trace.go:171","msg":"trace[2009724229] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"162.605054ms","start":"2026-08-13T00:58:38.579934Z","end":"2026-08-13T00:58:38.742539Z","steps":["trace[2009724229] 'process raft request' (duration: 162.441579ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:58:40.229746Z","caller":"traceutil/trace.go:171","msg":"trace[961960173] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"132.187317ms","start":"2026-08-13T00:58:40.097537Z","end":"2026-08-13T00:58:40.229724Z","steps":["trace[961960173] 'process raft request' (duration: 118.57914ms)","trace[961960173] 'compare' (duration: 13.509534ms)"],"step_count":2}