{"level":"warn","ts":"2026-08-05T21:34:00.029988Z","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-05T21:34:00.030084Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138: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-05T21:34:00.030149Z","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-05T21:34:00.030159Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-08-05T21:34:00.030179Z","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-05T21:34:00.031756Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-08-05T21:34:00.031865Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138: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-05T21:34:00.039105Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.05358ms"} {"level":"info","ts":"2026-08-05T21:34:00.049432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-08-05T21:34:00.049532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:34:00.049572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-08-05T21:34:00.0496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:34:00.049616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-08-05T21:34:00.049673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-08-05T21:34:00.055823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:34:00.060593Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:34:00.064219Z","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-05T21:34:00.071553Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:34:00.072528Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:34:00.073187Z","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-05T21:34:00.07673Z","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-05T21:34:00.076746Z","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-05T21:34:00.079514Z","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-05T21:34:00.079651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-08-05T21:34:00.079791Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-08-05T21:34:00.079824Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:34:00.079874Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:34:00.07995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-08-05T21:34:00.080112Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-08-05T21:34:00.650637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:34:00.651282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:34:00.651433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-08-05T21:34:00.651588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:34:00.651679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-08-05T21:34:00.651786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-08-05T21:34:00.651867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-08-05T21:34:00.656943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:34:00.658037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:34:00.658112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:34:00.658024Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:34:00.658634Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:34:00.658721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:34:00.662635Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:34:00.662819Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:34:00.663064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:34:00.663186Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:34:00.667305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"warn","ts":"2026-08-05T21:34:25.076098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.811793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:34:25.076258Z","caller":"traceutil/trace.go:171","msg":"trace[1995283494] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"185.784544ms","start":"2026-08-05T21:34:24.890453Z","end":"2026-08-05T21:34:25.076237Z","steps":["trace[1995283494] 'read index received' (duration: 36.819166ms)","trace[1995283494] 'applied index is now lower than readState.Index' (duration: 148.964128ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:25.076308Z","caller":"traceutil/trace.go:171","msg":"trace[878356483] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"240.889702ms","start":"2026-08-05T21:34:24.835379Z","end":"2026-08-05T21:34:25.076269Z","steps":["trace[878356483] 'process raft request' (duration: 91.962635ms)","trace[878356483] 'compare' (duration: 147.599418ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:25.076536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.819954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:34:25.076581Z","caller":"traceutil/trace.go:171","msg":"trace[1510248801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:392; }","duration":"103.871995ms","start":"2026-08-05T21:34:24.972697Z","end":"2026-08-05T21:34:25.076569Z","steps":["trace[1510248801] 'agreement among raft nodes before linearized reading' (duration: 103.771173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:25.076566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.08181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-08-05T21:34:25.076811Z","caller":"traceutil/trace.go:171","msg":"trace[272118975] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:392; }","duration":"186.359896ms","start":"2026-08-05T21:34:24.890389Z","end":"2026-08-05T21:34:25.076748Z","steps":["trace[272118975] 'agreement among raft nodes before linearized reading' (duration: 186.016698ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.237993Z","caller":"traceutil/trace.go:171","msg":"trace[703764589] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"109.160676ms","start":"2026-08-05T21:34:25.128801Z","end":"2026-08-05T21:34:25.237961Z","steps":["trace[703764589] 'process raft request' (duration: 98.405077ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.464197Z","caller":"traceutil/trace.go:171","msg":"trace[158095103] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:414; }","duration":"127.759602ms","start":"2026-08-05T21:34:25.336415Z","end":"2026-08-05T21:34:25.464175Z","steps":["trace[158095103] 'read index received' (duration: 34.7363ms)","trace[158095103] 'applied index is now lower than readState.Index' (duration: 93.022352ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:25.464243Z","caller":"traceutil/trace.go:171","msg":"trace[63324765] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"161.925159ms","start":"2026-08-05T21:34:25.302264Z","end":"2026-08-05T21:34:25.464189Z","steps":["trace[63324765] 'process raft request' (duration: 161.789176ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.464283Z","caller":"traceutil/trace.go:171","msg":"trace[1349225766] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"159.854576ms","start":"2026-08-05T21:34:25.304374Z","end":"2026-08-05T21:34:25.464228Z","steps":["trace[1349225766] 'process raft request' (duration: 159.717883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:25.464378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.934475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:34:25.464408Z","caller":"traceutil/trace.go:171","msg":"trace[238547384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"128.011268ms","start":"2026-08-05T21:34:25.336387Z","end":"2026-08-05T21:34:25.464399Z","steps":["trace[238547384] 'agreement among raft nodes before linearized reading' (duration: 127.872795ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.464622Z","caller":"traceutil/trace.go:171","msg":"trace[131769983] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"162.7788ms","start":"2026-08-05T21:34:25.301828Z","end":"2026-08-05T21:34:25.464607Z","steps":["trace[131769983] 'process raft request' (duration: 69.248866ms)","trace[131769983] 'compare' (duration: 92.852249ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:25.464698Z","caller":"traceutil/trace.go:171","msg":"trace[460161194] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"160.156434ms","start":"2026-08-05T21:34:25.304532Z","end":"2026-08-05T21:34:25.464688Z","steps":["trace[460161194] 'process raft request' (duration: 159.601841ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.467858Z","caller":"traceutil/trace.go:171","msg":"trace[720841514] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"125.354012ms","start":"2026-08-05T21:34:25.342458Z","end":"2026-08-05T21:34:25.467812Z","steps":["trace[720841514] 'process raft request' (duration: 125.214849ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.714838Z","caller":"traceutil/trace.go:171","msg":"trace[1201606342] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:419; }","duration":"239.886052ms","start":"2026-08-05T21:34:25.474931Z","end":"2026-08-05T21:34:25.714817Z","steps":["trace[1201606342] 'read index received' (duration: 13.262613ms)","trace[1201606342] 'applied index is now lower than readState.Index' (duration: 226.622469ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:25.71497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.021354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-08-05T21:34:25.714994Z","caller":"traceutil/trace.go:171","msg":"trace[1748441543] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:407; }","duration":"240.105506ms","start":"2026-08-05T21:34:25.47488Z","end":"2026-08-05T21:34:25.714985Z","steps":["trace[1748441543] 'agreement among raft nodes before linearized reading' (duration: 240.023794ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.715184Z","caller":"traceutil/trace.go:171","msg":"trace[1648355396] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"241.015116ms","start":"2026-08-05T21:34:25.474132Z","end":"2026-08-05T21:34:25.715147Z","steps":["trace[1648355396] 'process raft request' (duration: 240.633708ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:25.715226Z","caller":"traceutil/trace.go:171","msg":"trace[1977943925] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"241.101567ms","start":"2026-08-05T21:34:25.474088Z","end":"2026-08-05T21:34:25.715189Z","steps":["trace[1977943925] 'process raft request' (duration: 162.183096ms)","trace[1977943925] 'compare' (duration: 78.309259ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:41.834985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:34:42.335555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:34:42.45063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147774889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-05T21:34:42.450768Z","caller":"traceutil/trace.go:171","msg":"trace[1815714880] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"1.147975704s","start":"2026-08-05T21:34:41.302765Z","end":"2026-08-05T21:34:42.450741Z","steps":["trace[1815714880] 'range keys from in-memory index tree' (duration: 1.147539274s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:42.450816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:41.302747Z","time spent":"1.148058315s","remote":"127.0.0.1:51792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-05T21:34:42.520808Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211191929s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:34:42.521395Z","caller":"traceutil/trace.go:171","msg":"trace[1053757347] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.211928883s","start":"2026-08-05T21:34:41.309436Z","end":"2026-08-05T21:34:42.521365Z","steps":["trace[1053757347] 'process raft request' (duration: 1.211669758s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:42.5216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:41.309425Z","time spent":"1.212057646s","remote":"127.0.0.1:51792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:34:42.623619Z","caller":"traceutil/trace.go:171","msg":"trace[1931077746] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"1.290992414s","start":"2026-08-05T21:34:41.332598Z","end":"2026-08-05T21:34:42.623591Z","steps":["trace[1931077746] 'read index received' (duration: 1.18883663s)","trace[1931077746] 'applied index is now lower than readState.Index' (duration: 102.154934ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:42.623817Z","caller":"traceutil/trace.go:171","msg":"trace[1532332570] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.133329315s","start":"2026-08-05T21:34:41.490478Z","end":"2026-08-05T21:34:42.623808Z","steps":["trace[1532332570] 'process raft request' (duration: 1.124120016s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:42.623971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:41.490444Z","time spent":"1.133442527s","remote":"127.0.0.1:51792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:34:42.623944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29130028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:34:42.624154Z","caller":"traceutil/trace.go:171","msg":"trace[867345383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.291597716s","start":"2026-08-05T21:34:41.332536Z","end":"2026-08-05T21:34:42.624133Z","steps":["trace[867345383] 'agreement among raft nodes before linearized reading' (duration: 1.29130928s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:42.62421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:41.332508Z","time spent":"1.291688188s","remote":"127.0.0.1:51734","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-05T21:34:42.633338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.81795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-08-05T21:34:42.633388Z","caller":"traceutil/trace.go:171","msg":"trace[1892060842] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"169.923692ms","start":"2026-08-05T21:34:42.463451Z","end":"2026-08-05T21:34:42.633375Z","steps":["trace[1892060842] 'agreement among raft nodes before linearized reading' (duration: 169.80003ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:44.711795Z","caller":"traceutil/trace.go:171","msg":"trace[903322235] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"102.218136ms","start":"2026-08-05T21:34:44.609558Z","end":"2026-08-05T21:34:44.711777Z","steps":["trace[903322235] 'process raft request' (duration: 31.974832ms)","trace[903322235] 'compare' (duration: 70.124561ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:44.716865Z","caller":"traceutil/trace.go:171","msg":"trace[1502742956] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"107.083225ms","start":"2026-08-05T21:34:44.609755Z","end":"2026-08-05T21:34:44.716838Z","steps":["trace[1502742956] 'process raft request' (duration: 106.945913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:45.440142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.553018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:34:45.440295Z","caller":"traceutil/trace.go:171","msg":"trace[1301728401] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"293.364455ms","start":"2026-08-05T21:34:45.146906Z","end":"2026-08-05T21:34:45.440271Z","steps":["trace[1301728401] 'read index received' (duration: 72.692µs)","trace[1301728401] 'applied index is now lower than readState.Index' (duration: 293.290143ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:45.440345Z","caller":"traceutil/trace.go:171","msg":"trace[805891688] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"355.98299ms","start":"2026-08-05T21:34:45.0843Z","end":"2026-08-05T21:34:45.440283Z","steps":["trace[805891688] 'process raft request' (duration: 38.177167ms)","trace[805891688] 'compare' (duration: 317.362374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:45.440469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:45.084284Z","time spent":"356.125153ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:34:45.440524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.572059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:34:45.440593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.89881ms","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-05T21:34:45.440631Z","caller":"traceutil/trace.go:171","msg":"trace[933459220] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"293.743162ms","start":"2026-08-05T21:34:45.146861Z","end":"2026-08-05T21:34:45.440604Z","steps":["trace[933459220] 'agreement among raft nodes before linearized reading' (duration: 293.508608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:45.440648Z","caller":"traceutil/trace.go:171","msg":"trace[2089624647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"167.969981ms","start":"2026-08-05T21:34:45.27266Z","end":"2026-08-05T21:34:45.440629Z","steps":["trace[2089624647] 'agreement among raft nodes before linearized reading' (duration: 167.791217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:45.441162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.163199ms","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-05T21:34:45.441238Z","caller":"traceutil/trace.go:171","msg":"trace[1989304639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"151.251621ms","start":"2026-08-05T21:34:45.289968Z","end":"2026-08-05T21:34:45.441219Z","steps":["trace[1989304639] 'agreement among raft nodes before linearized reading' (duration: 151.051597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:45.441239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.150929ms","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-05T21:34:45.441313Z","caller":"traceutil/trace.go:171","msg":"trace[1504778237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"151.23594ms","start":"2026-08-05T21:34:45.290061Z","end":"2026-08-05T21:34:45.441297Z","steps":["trace[1504778237] 'agreement among raft nodes before linearized reading' (duration: 150.715189ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:45.613041Z","caller":"traceutil/trace.go:171","msg":"trace[415692624] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"165.498631ms","start":"2026-08-05T21:34:45.447507Z","end":"2026-08-05T21:34:45.613006Z","steps":["trace[415692624] 'process raft request' (duration: 165.386049ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:45.613102Z","caller":"traceutil/trace.go:171","msg":"trace[1059237015] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"165.788196ms","start":"2026-08-05T21:34:45.447271Z","end":"2026-08-05T21:34:45.613059Z","steps":["trace[1059237015] 'process raft request' (duration: 136.873217ms)","trace[1059237015] 'compare' (duration: 28.573132ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:45.613374Z","caller":"traceutil/trace.go:171","msg":"trace[2132140273] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"165.49798ms","start":"2026-08-05T21:34:45.447865Z","end":"2026-08-05T21:34:45.613363Z","steps":["trace[2132140273] 'process raft request' (duration: 165.094002ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:46.544567Z","caller":"traceutil/trace.go:171","msg":"trace[2038967243] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"206.591448ms","start":"2026-08-05T21:34:46.337945Z","end":"2026-08-05T21:34:46.544536Z","steps":["trace[2038967243] 'read index received' (duration: 203.70245ms)","trace[2038967243] 'applied index is now lower than readState.Index' (duration: 2.887948ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:46.544709Z","caller":"traceutil/trace.go:171","msg":"trace[359072149] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"670.689016ms","start":"2026-08-05T21:34:45.873937Z","end":"2026-08-05T21:34:46.544626Z","steps":["trace[359072149] 'process raft request' (duration: 668.632595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:46.544742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.782203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:34:46.544831Z","caller":"traceutil/trace.go:171","msg":"trace[912052878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"206.945036ms","start":"2026-08-05T21:34:46.337867Z","end":"2026-08-05T21:34:46.544812Z","steps":["trace[912052878] 'agreement among raft nodes before linearized reading' (duration: 206.774442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:46.544892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:45.873909Z","time spent":"670.87768ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:34:48.411478Z","caller":"traceutil/trace.go:171","msg":"trace[691293197] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"144.951463ms","start":"2026-08-05T21:34:48.26647Z","end":"2026-08-05T21:34:48.411421Z","steps":["trace[691293197] 'process raft request' (duration: 119.537139ms)","trace[691293197] 'compare' (duration: 24.995096ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:49.09714Z","caller":"traceutil/trace.go:171","msg":"trace[1294124040] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"151.417141ms","start":"2026-08-05T21:34:48.945697Z","end":"2026-08-05T21:34:49.097115Z","steps":["trace[1294124040] 'process raft request' (duration: 108.8117ms)","trace[1294124040] 'compare' (duration: 42.392757ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:49.278973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.470136ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T21:34:50.364217Z","caller":"traceutil/trace.go:171","msg":"trace[470610163] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"259.450119ms","start":"2026-08-05T21:34:50.104727Z","end":"2026-08-05T21:34:50.364178Z","steps":["trace[470610163] 'process raft request' (duration: 251.478518ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:50.990819Z","caller":"traceutil/trace.go:171","msg":"trace[1307952989] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"654.605043ms","start":"2026-08-05T21:34:50.336178Z","end":"2026-08-05T21:34:50.990783Z","steps":["trace[1307952989] 'read index received' (duration: 19.951172ms)","trace[1307952989] 'applied index is now lower than readState.Index' (duration: 634.652481ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:50.99093Z","caller":"traceutil/trace.go:171","msg":"trace[1381949347] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"884.392904ms","start":"2026-08-05T21:34:50.106487Z","end":"2026-08-05T21:34:50.99088Z","steps":["trace[1381949347] 'process raft request' (duration: 884.024727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:50.991053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.858428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:34:50.991132Z","caller":"traceutil/trace.go:171","msg":"trace[1901254816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"655.001071ms","start":"2026-08-05T21:34:50.336111Z","end":"2026-08-05T21:34:50.991112Z","steps":["trace[1901254816] 'agreement among raft nodes before linearized reading' (duration: 654.826427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:50.991233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:50.336084Z","time spent":"655.126084ms","remote":"127.0.0.1:51742","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-05T21:34:50.991128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:50.106458Z","time spent":"884.575058ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:34:51.716654Z","caller":"traceutil/trace.go:171","msg":"trace[536708435] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"678.512166ms","start":"2026-08-05T21:34:51.038119Z","end":"2026-08-05T21:34:51.716631Z","steps":["trace[536708435] 'process raft request' (duration: 678.373103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.716811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.038095Z","time spent":"678.641229ms","remote":"127.0.0.1:51834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:34:51.807307Z","caller":"traceutil/trace.go:171","msg":"trace[552213763] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"769.124758ms","start":"2026-08-05T21:34:51.038135Z","end":"2026-08-05T21:34:51.80726Z","steps":["trace[552213763] 'process raft request' (duration: 768.845563ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:51.807329Z","caller":"traceutil/trace.go:171","msg":"trace[1969975532] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"767.749571ms","start":"2026-08-05T21:34:51.039546Z","end":"2026-08-05T21:34:51.807295Z","steps":["trace[1969975532] 'process raft request' (duration: 767.595928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.807546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.038113Z","time spent":"769.299171ms","remote":"127.0.0.1:51834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:34:51.807604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.449217ms","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-05T21:34:51.807658Z","caller":"traceutil/trace.go:171","msg":"trace[792193537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"343.57428ms","start":"2026-08-05T21:34:51.46407Z","end":"2026-08-05T21:34:51.807644Z","steps":["trace[792193537] 'agreement among raft nodes before linearized reading' (duration: 343.313375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.807713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.464046Z","time spent":"343.657721ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T21:34:51.807309Z","caller":"traceutil/trace.go:171","msg":"trace[2067168942] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"343.144012ms","start":"2026-08-05T21:34:51.464128Z","end":"2026-08-05T21:34:51.807272Z","steps":["trace[2067168942] 'read index received' (duration: 252.422637ms)","trace[2067168942] 'applied index is now lower than readState.Index' (duration: 90.720235ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:51.807842Z","caller":"traceutil/trace.go:171","msg":"trace[843099368] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"766.7096ms","start":"2026-08-05T21:34:51.041047Z","end":"2026-08-05T21:34:51.807757Z","steps":["trace[843099368] 'process raft request' (duration: 766.1713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.808175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.039531Z","time spent":"767.939515ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:34:51.808243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.912033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T21:34:51.808297Z","caller":"traceutil/trace.go:171","msg":"trace[504362912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"135.021105ms","start":"2026-08-05T21:34:51.673262Z","end":"2026-08-05T21:34:51.808283Z","steps":["trace[504362912] 'agreement among raft nodes before linearized reading' (duration: 134.819842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.808355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.155958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:34:51.808459Z","caller":"traceutil/trace.go:171","msg":"trace[1301850043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"135.293681ms","start":"2026-08-05T21:34:51.673138Z","end":"2026-08-05T21:34:51.808432Z","steps":["trace[1301850043] 'agreement among raft nodes before linearized reading' (duration: 135.024465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:51.810209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.565897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-05T21:34:51.810344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.041036Z","time spent":"766.898094ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:34:51.811581Z","caller":"traceutil/trace.go:171","msg":"trace[1208341414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"137.780071ms","start":"2026-08-05T21:34:51.672555Z","end":"2026-08-05T21:34:51.810335Z","steps":["trace[1208341414] 'agreement among raft nodes before linearized reading' (duration: 135.527276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:52.836237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597150,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:34:53.156625Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.341743642s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:34:53.157104Z","caller":"traceutil/trace.go:171","msg":"trace[1994304563] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.342259993s","start":"2026-08-05T21:34:51.814807Z","end":"2026-08-05T21:34:53.157067Z","steps":["trace[1994304563] 'process raft request' (duration: 1.342042259s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:53.157303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.814786Z","time spent":"1.342424276s","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:34:54.293068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.397456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:34:54.293302Z","caller":"traceutil/trace.go:171","msg":"trace[1224889095] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"1.957617906s","start":"2026-08-05T21:34:52.335664Z","end":"2026-08-05T21:34:54.293282Z","steps":["trace[1224889095] 'read index received' (duration: 821.174315ms)","trace[1224889095] 'applied index is now lower than readState.Index' (duration: 1.136442621s)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:54.293363Z","caller":"traceutil/trace.go:171","msg":"trace[1593111108] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.478410437s","start":"2026-08-05T21:34:51.814887Z","end":"2026-08-05T21:34:54.293297Z","steps":["trace[1593111108] 'process raft request' (duration: 2.308690954s)","trace[1593111108] 'compare' (duration: 169.165912ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:54.293435Z","caller":"traceutil/trace.go:171","msg":"trace[356481338] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"2.478436928s","start":"2026-08-05T21:34:51.814936Z","end":"2026-08-05T21:34:54.293373Z","steps":["trace[356481338] 'process raft request' (duration: 2.478236603s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:54.293545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.814849Z","time spent":"2.478594811s","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:34:54.29375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958085816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:34:54.293808Z","caller":"traceutil/trace.go:171","msg":"trace[1026718818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.958185278s","start":"2026-08-05T21:34:52.335608Z","end":"2026-08-05T21:34:54.293793Z","steps":["trace[1026718818] 'agreement among raft nodes before linearized reading' (duration: 1.958056876s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:54.29392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:52.335584Z","time spent":"1.958253439s","remote":"127.0.0.1:51742","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-05T21:34:54.294169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.814912Z","time spent":"2.479158243s","remote":"127.0.0.1:52058","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-05T21:34:54.29422Z","caller":"traceutil/trace.go:171","msg":"trace[837695542] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"2.478920918s","start":"2026-08-05T21:34:51.815263Z","end":"2026-08-05T21:34:54.294183Z","steps":["trace[837695542] 'process raft request' (duration: 2.477963278s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:54.294594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:51.815254Z","time spent":"2.479156562s","remote":"127.0.0.1:52058","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-05T21:34:54.403443Z","caller":"traceutil/trace.go:171","msg":"trace[320726651] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"110.08179ms","start":"2026-08-05T21:34:54.293339Z","end":"2026-08-05T21:34:54.403421Z","steps":["trace[320726651] 'read index received' (duration: 107.711633ms)","trace[320726651] 'applied index is now lower than readState.Index' (duration: 2.368457ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:54.403525Z","caller":"traceutil/trace.go:171","msg":"trace[1869336401] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"829.706081ms","start":"2026-08-05T21:34:53.57378Z","end":"2026-08-05T21:34:54.403486Z","steps":["trace[1869336401] 'process raft request' (duration: 827.414985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:54.403721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:53.573751Z","time spent":"829.865725ms","remote":"127.0.0.1:52058","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-05T21:34:54.403854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.634922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:34:54.403952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.019006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-05T21:34:54.403966Z","caller":"traceutil/trace.go:171","msg":"trace[2031087349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"108.758454ms","start":"2026-08-05T21:34:54.295179Z","end":"2026-08-05T21:34:54.403938Z","steps":["trace[2031087349] 'agreement among raft nodes before linearized reading' (duration: 108.573621ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:54.404006Z","caller":"traceutil/trace.go:171","msg":"trace[978044075] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:621; }","duration":"295.176679ms","start":"2026-08-05T21:34:54.108816Z","end":"2026-08-05T21:34:54.403993Z","steps":["trace[978044075] 'agreement among raft nodes before linearized reading' (duration: 294.869003ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:56.697658Z","caller":"traceutil/trace.go:171","msg":"trace[1163089012] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"397.978193ms","start":"2026-08-05T21:34:56.299655Z","end":"2026-08-05T21:34:56.697633Z","steps":["trace[1163089012] 'read index received' (duration: 308.963481ms)","trace[1163089012] 'applied index is now lower than readState.Index' (duration: 89.011332ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:34:56.697822Z","caller":"traceutil/trace.go:171","msg":"trace[962740886] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"432.124323ms","start":"2026-08-05T21:34:56.265664Z","end":"2026-08-05T21:34:56.697789Z","steps":["trace[962740886] 'process raft request' (duration: 343.005699ms)","trace[962740886] 'compare' (duration: 88.810328ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:34:56.697939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.27454ms","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-05T21:34:56.697978Z","caller":"traceutil/trace.go:171","msg":"trace[1056822177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"398.384121ms","start":"2026-08-05T21:34:56.299581Z","end":"2026-08-05T21:34:56.697965Z","steps":["trace[1056822177] 'agreement among raft nodes before linearized reading' (duration: 398.180007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:56.698018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:56.299563Z","time spent":"398.450612ms","remote":"127.0.0.1:52058","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-05T21:34:56.697972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:56.265636Z","time spent":"432.261716ms","remote":"127.0.0.1:51792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:34:56.698065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.399142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:34:56.698154Z","caller":"traceutil/trace.go:171","msg":"trace[2020500782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"398.527624ms","start":"2026-08-05T21:34:56.299606Z","end":"2026-08-05T21:34:56.698133Z","steps":["trace[2020500782] 'agreement among raft nodes before linearized reading' (duration: 398.151106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:56.69821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:56.299589Z","time spent":"398.613896ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T21:34:56.705633Z","caller":"traceutil/trace.go:171","msg":"trace[1972436851] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"238.340145ms","start":"2026-08-05T21:34:56.46728Z","end":"2026-08-05T21:34:56.70562Z","steps":["trace[1972436851] 'process raft request' (duration: 238.239493ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:34:56.778226Z","caller":"traceutil/trace.go:171","msg":"trace[1723254006] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"108.889978ms","start":"2026-08-05T21:34:56.669286Z","end":"2026-08-05T21:34:56.778176Z","steps":["trace[1723254006] 'process raft request' (duration: 108.52433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:56.778877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.996847ms","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-05T21:34:56.778977Z","caller":"traceutil/trace.go:171","msg":"trace[1082344272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"479.15106ms","start":"2026-08-05T21:34:56.299804Z","end":"2026-08-05T21:34:56.778955Z","steps":["trace[1082344272] 'agreement among raft nodes before linearized reading' (duration: 478.757063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:34:56.779023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:34:56.299797Z","time spent":"479.216581ms","remote":"127.0.0.1:52058","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-05T21:35:07.389702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597412,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T21:35:07.406495Z","caller":"traceutil/trace.go:171","msg":"trace[1517533244] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"542.366176ms","start":"2026-08-05T21:35:06.864103Z","end":"2026-08-05T21:35:07.406469Z","steps":["trace[1517533244] 'process raft request' (duration: 541.294805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:07.406744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:06.864072Z","time spent":"542.533409ms","remote":"127.0.0.1:52058","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-05T21:35:07.44103Z","caller":"traceutil/trace.go:171","msg":"trace[450995578] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"551.631166ms","start":"2026-08-05T21:35:06.889349Z","end":"2026-08-05T21:35:07.44098Z","steps":["trace[450995578] 'read index received' (duration: 516.111193ms)","trace[450995578] 'applied index is now lower than readState.Index' (duration: 35.518763ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:35:07.441112Z","caller":"traceutil/trace.go:171","msg":"trace[830063632] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"350.99436ms","start":"2026-08-05T21:35:07.090062Z","end":"2026-08-05T21:35:07.441056Z","steps":["trace[830063632] 'process raft request' (duration: 350.692744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:07.441305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.942821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:35:07.441413Z","caller":"traceutil/trace.go:171","msg":"trace[838175336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"552.114885ms","start":"2026-08-05T21:35:06.889232Z","end":"2026-08-05T21:35:07.441347Z","steps":["trace[838175336] 'agreement among raft nodes before linearized reading' (duration: 551.88521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:07.441464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:06.889192Z","time spent":"552.262518ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T21:35:07.441579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:07.090011Z","time spent":"351.230465ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:35:07.542365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.8949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-05T21:35:07.542438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.686149ms","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-05T21:35:07.542491Z","caller":"traceutil/trace.go:171","msg":"trace[545452731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"629.112735ms","start":"2026-08-05T21:35:06.913352Z","end":"2026-08-05T21:35:07.542465Z","steps":["trace[545452731] 'agreement among raft nodes before linearized reading' (duration: 527.993024ms)","trace[545452731] 'range keys from in-memory index tree' (duration: 100.766624ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:35:07.542561Z","caller":"traceutil/trace.go:171","msg":"trace[167828819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"224.846983ms","start":"2026-08-05T21:35:07.317667Z","end":"2026-08-05T21:35:07.542514Z","steps":["trace[167828819] 'agreement among raft nodes before linearized reading' (duration: 123.66037ms)","trace[167828819] 'range keys from in-memory index tree' (duration: 100.842115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:35:07.542558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:06.91322Z","time spent":"629.324469ms","remote":"127.0.0.1:52058","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-05T21:35:11.937769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:35:12.43819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159206326597531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:35:12.485721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.243471628s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:35:12.486201Z","caller":"traceutil/trace.go:171","msg":"trace[1373600157] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"1.048514886s","start":"2026-08-05T21:35:11.437656Z","end":"2026-08-05T21:35:12.486171Z","steps":["trace[1373600157] 'read index received' (duration: 1.048244711s)","trace[1373600157] 'applied index is now lower than readState.Index' (duration: 268.955µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:35:12.486462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.537357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-05T21:35:12.486486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048813452s","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-05T21:35:12.48652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.36753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:35:12.486518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.43937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-05T21:35:12.486548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.891785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:35:12.486595Z","caller":"traceutil/trace.go:171","msg":"trace[386640758] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:771; }","duration":"533.504503ms","start":"2026-08-05T21:35:11.953072Z","end":"2026-08-05T21:35:12.486576Z","steps":["trace[386640758] 'agreement among raft nodes before linearized reading' (duration: 533.36083ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:35:12.486589Z","caller":"traceutil/trace.go:171","msg":"trace[982903325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"1.048981235s","start":"2026-08-05T21:35:11.437586Z","end":"2026-08-05T21:35:12.486567Z","steps":["trace[982903325] 'agreement among raft nodes before linearized reading' (duration: 1.04870267s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.486602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.112582ms","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-05T21:35:12.486685Z","caller":"traceutil/trace.go:171","msg":"trace[206296343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"920.217174ms","start":"2026-08-05T21:35:11.566458Z","end":"2026-08-05T21:35:12.486675Z","steps":["trace[206296343] 'agreement among raft nodes before linearized reading' (duration: 920.080021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.486686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.953041Z","time spent":"533.631776ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:35:12.486688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.437554Z","time spent":"1.049124369s","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T21:35:12.486706Z","caller":"traceutil/trace.go:171","msg":"trace[1112574112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"150.027618ms","start":"2026-08-05T21:35:12.336604Z","end":"2026-08-05T21:35:12.486631Z","steps":["trace[1112574112] 'agreement among raft nodes before linearized reading' (duration: 149.846485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.48673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.566446Z","time spent":"920.275045ms","remote":"127.0.0.1:52058","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-05T21:35:12.486613Z","caller":"traceutil/trace.go:171","msg":"trace[1137728762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"259.590643ms","start":"2026-08-05T21:35:12.226997Z","end":"2026-08-05T21:35:12.486588Z","steps":["trace[1137728762] 'agreement among raft nodes before linearized reading' (duration: 259.347079ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:35:12.486513Z","caller":"traceutil/trace.go:171","msg":"trace[858795087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"925.647729ms","start":"2026-08-05T21:35:11.560849Z","end":"2026-08-05T21:35:12.486497Z","steps":["trace[858795087] 'agreement among raft nodes before linearized reading' (duration: 925.470006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.486857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.560831Z","time spent":"926.006957ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T21:35:12.486325Z","caller":"traceutil/trace.go:171","msg":"trace[190432534] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.244193291s","start":"2026-08-05T21:35:11.242034Z","end":"2026-08-05T21:35:12.486227Z","steps":["trace[190432534] 'process raft request' (duration: 1.243934496s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.487139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.8924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T21:35:12.487548Z","caller":"traceutil/trace.go:171","msg":"trace[111626661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"886.777527ms","start":"2026-08-05T21:35:11.60075Z","end":"2026-08-05T21:35:12.487527Z","steps":["trace[111626661] 'agreement among raft nodes before linearized reading' (duration: 885.621635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:12.487709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.600716Z","time spent":"886.977131ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:35:12.487243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:11.241991Z","time spent":"1.245042068s","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:35:27.011725Z","caller":"traceutil/trace.go:171","msg":"trace[1380228090] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"432.668982ms","start":"2026-08-05T21:35:26.579024Z","end":"2026-08-05T21:35:27.011693Z","steps":["trace[1380228090] 'read index received' (duration: 432.424397ms)","trace[1380228090] 'applied index is now lower than readState.Index' (duration: 243.775µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:35:27.011768Z","caller":"traceutil/trace.go:171","msg":"trace[1407117915] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"438.799128ms","start":"2026-08-05T21:35:26.572949Z","end":"2026-08-05T21:35:27.011748Z","steps":["trace[1407117915] 'process raft request' (duration: 438.559234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:27.011993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.705807ms","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-05T21:35:27.01206Z","caller":"traceutil/trace.go:171","msg":"trace[1062998444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"420.816718ms","start":"2026-08-05T21:35:26.591222Z","end":"2026-08-05T21:35:27.012039Z","steps":["trace[1062998444] 'agreement among raft nodes before linearized reading' (duration: 420.644075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:27.012107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:26.591203Z","time spent":"420.89199ms","remote":"127.0.0.1:52058","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-05T21:35:27.012138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:26.572923Z","time spent":"438.909591ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:35:27.01199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.959007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-05T21:35:27.01214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.009841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-cfwj7\" ","response":"range_response_count:1 size:5677"} {"level":"warn","ts":"2026-08-05T21:35:27.012217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.881964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T21:35:27.012231Z","caller":"traceutil/trace.go:171","msg":"trace[990840289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"433.235022ms","start":"2026-08-05T21:35:26.578963Z","end":"2026-08-05T21:35:27.012198Z","steps":["trace[990840289] 'agreement among raft nodes before linearized reading' (duration: 432.865915ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:35:27.012254Z","caller":"traceutil/trace.go:171","msg":"trace[170942320] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-cfwj7; range_end:; response_count:1; response_revision:932; }","duration":"292.184285ms","start":"2026-08-05T21:35:26.720052Z","end":"2026-08-05T21:35:27.012236Z","steps":["trace[170942320] 'agreement among raft nodes before linearized reading' (duration: 291.994141ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:35:27.01228Z","caller":"traceutil/trace.go:171","msg":"trace[1440544205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"408.987456ms","start":"2026-08-05T21:35:26.603275Z","end":"2026-08-05T21:35:27.012262Z","steps":["trace[1440544205] 'agreement among raft nodes before linearized reading' (duration: 408.811523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:27.01232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:26.603249Z","time spent":"409.060727ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:35:27.012328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:26.578932Z","time spent":"433.362666ms","remote":"127.0.0.1:52058","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-05T21:35:27.012447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.18295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:35:27.012564Z","caller":"traceutil/trace.go:171","msg":"trace[2086762372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"409.359974ms","start":"2026-08-05T21:35:26.603177Z","end":"2026-08-05T21:35:27.012537Z","steps":["trace[2086762372] 'agreement among raft nodes before linearized reading' (duration: 408.966976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:35:27.012627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:35:26.603161Z","time spent":"409.455025ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T21:35:28.504811Z","caller":"traceutil/trace.go:171","msg":"trace[53620236] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"161.212818ms","start":"2026-08-05T21:35:28.343572Z","end":"2026-08-05T21:35:28.504785Z","steps":["trace[53620236] 'process raft request' (duration: 161.048095ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:36:03.88821Z","caller":"traceutil/trace.go:171","msg":"trace[1539566973] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"424.583022ms","start":"2026-08-05T21:36:03.463575Z","end":"2026-08-05T21:36:03.888158Z","steps":["trace[1539566973] 'process raft request' (duration: 342.148591ms)","trace[1539566973] 'compare' (duration: 81.278779ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:36:03.88826Z","caller":"traceutil/trace.go:171","msg":"trace[496121086] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"424.301457ms","start":"2026-08-05T21:36:03.463928Z","end":"2026-08-05T21:36:03.88823Z","steps":["trace[496121086] 'process raft request' (duration: 424.152474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:36:03.888415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:36:03.463549Z","time spent":"424.762394ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:36:03.888432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:36:03.463917Z","time spent":"424.419389ms","remote":"127.0.0.1:52058","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-05T21:36:03.889037Z","caller":"traceutil/trace.go:171","msg":"trace[1143045218] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1113; }","duration":"420.137972ms","start":"2026-08-05T21:36:03.468881Z","end":"2026-08-05T21:36:03.889019Z","steps":["trace[1143045218] 'read index received' (duration: 336.842806ms)","trace[1143045218] 'applied index is now lower than readState.Index' (duration: 83.293356ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:36:03.889205Z","caller":"traceutil/trace.go:171","msg":"trace[1653109422] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"271.062964ms","start":"2026-08-05T21:36:03.618127Z","end":"2026-08-05T21:36:03.88919Z","steps":["trace[1653109422] 'process raft request' (duration: 270.537984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:36:03.889477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.592649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:36:03.889536Z","caller":"traceutil/trace.go:171","msg":"trace[302743758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1075; }","duration":"420.677601ms","start":"2026-08-05T21:36:03.468843Z","end":"2026-08-05T21:36:03.889521Z","steps":["trace[302743758] 'agreement among raft nodes before linearized reading' (duration: 420.485878ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:36:03.888794Z","caller":"traceutil/trace.go:171","msg":"trace[855018586] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"424.378238ms","start":"2026-08-05T21:36:03.464384Z","end":"2026-08-05T21:36:03.888762Z","steps":["trace[855018586] 'process raft request' (duration: 423.807748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:36:03.889601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:36:03.46883Z","time spent":"420.756122ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T21:36:03.88967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.990623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:36:03.889734Z","caller":"traceutil/trace.go:171","msg":"trace[1105960383] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1075; }","duration":"298.116816ms","start":"2026-08-05T21:36:03.591603Z","end":"2026-08-05T21:36:03.88972Z","steps":["trace[1105960383] 'agreement among raft nodes before linearized reading' (duration: 297.984673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:36:03.889738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:36:03.464357Z","time spent":"425.283523ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:36:22.141943Z","caller":"traceutil/trace.go:171","msg":"trace[1607655821] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"126.607464ms","start":"2026-08-05T21:36:22.015307Z","end":"2026-08-05T21:36:22.141915Z","steps":["trace[1607655821] 'process raft request' (duration: 36.722749ms)","trace[1607655821] 'compare' (duration: 89.700482ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:36:22.142308Z","caller":"traceutil/trace.go:171","msg":"trace[1388710134] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"125.693439ms","start":"2026-08-05T21:36:22.0166Z","end":"2026-08-05T21:36:22.142293Z","steps":["trace[1388710134] 'process raft request' (duration: 125.278762ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:36:22.142333Z","caller":"traceutil/trace.go:171","msg":"trace[1715735354] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"125.470546ms","start":"2026-08-05T21:36:22.016828Z","end":"2026-08-05T21:36:22.142299Z","steps":["trace[1715735354] 'process raft request' (duration: 125.313633ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:36:31.823937Z","caller":"traceutil/trace.go:171","msg":"trace[1946503981] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"168.329901ms","start":"2026-08-05T21:36:31.655563Z","end":"2026-08-05T21:36:31.823893Z","steps":["trace[1946503981] 'process raft request' (duration: 168.082937ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:01.855891Z","caller":"traceutil/trace.go:171","msg":"trace[1597434572] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"110.395457ms","start":"2026-08-05T21:37:01.745461Z","end":"2026-08-05T21:37:01.855856Z","steps":["trace[1597434572] 'process raft request' (duration: 110.238544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:02.279377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.797705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:37:02.279696Z","caller":"traceutil/trace.go:171","msg":"trace[1615731374] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"329.015332ms","start":"2026-08-05T21:37:01.950628Z","end":"2026-08-05T21:37:02.279644Z","steps":["trace[1615731374] 'read index received' (duration: 190.715419ms)","trace[1615731374] 'applied index is now lower than readState.Index' (duration: 138.294403ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:02.279667Z","caller":"traceutil/trace.go:171","msg":"trace[1028724418] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"412.863755ms","start":"2026-08-05T21:37:01.866732Z","end":"2026-08-05T21:37:02.279596Z","steps":["trace[1028724418] 'process raft request' (duration: 274.708085ms)","trace[1028724418] 'compare' (duration: 137.622141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:02.279941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.501105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-05T21:37:02.28Z","caller":"traceutil/trace.go:171","msg":"trace[97310122] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1274; }","duration":"251.677147ms","start":"2026-08-05T21:37:02.028304Z","end":"2026-08-05T21:37:02.279981Z","steps":["trace[97310122] 'agreement among raft nodes before linearized reading' (duration: 251.545465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:02.279985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:01.866707Z","time spent":"413.115239ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:37:02.279868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.200595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:02.280279Z","caller":"traceutil/trace.go:171","msg":"trace[137619217] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1274; }","duration":"329.591732ms","start":"2026-08-05T21:37:01.950579Z","end":"2026-08-05T21:37:02.280171Z","steps":["trace[137619217] 'agreement among raft nodes before linearized reading' (duration: 329.191345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:02.280579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.758369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:110228"} {"level":"info","ts":"2026-08-05T21:37:02.280653Z","caller":"traceutil/trace.go:171","msg":"trace[332904210] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1274; }","duration":"248.867171ms","start":"2026-08-05T21:37:02.031766Z","end":"2026-08-05T21:37:02.280633Z","steps":["trace[332904210] 'agreement among raft nodes before linearized reading' (duration: 248.511175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:02.28065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.385659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-05T21:37:02.280538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:01.950559Z","time spent":"329.957637ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:37:02.280707Z","caller":"traceutil/trace.go:171","msg":"trace[639699172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"179.490371ms","start":"2026-08-05T21:37:02.101198Z","end":"2026-08-05T21:37:02.280688Z","steps":["trace[639699172] 'agreement among raft nodes before linearized reading' (duration: 179.289048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:02.280681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.548002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3015e09f-3f59-4e22-9330-6383deda472f\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-08-05T21:37:02.280795Z","caller":"traceutil/trace.go:171","msg":"trace[1838242321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3015e09f-3f59-4e22-9330-6383deda472f; range_end:; response_count:1; response_revision:1274; }","duration":"110.342464ms","start":"2026-08-05T21:37:02.170427Z","end":"2026-08-05T21:37:02.28077Z","steps":["trace[1838242321] 'agreement among raft nodes before linearized reading' (duration: 109.44886ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:10.457589Z","caller":"traceutil/trace.go:171","msg":"trace[565550667] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"122.969402ms","start":"2026-08-05T21:37:10.334597Z","end":"2026-08-05T21:37:10.457566Z","steps":["trace[565550667] 'read index received' (duration: 122.85625ms)","trace[565550667] 'applied index is now lower than readState.Index' (duration: 109.092µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:10.457685Z","caller":"traceutil/trace.go:171","msg":"trace[1378905518] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"149.284239ms","start":"2026-08-05T21:37:10.30832Z","end":"2026-08-05T21:37:10.457604Z","steps":["trace[1378905518] 'process raft request' (duration: 149.071585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:10.457845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.194005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:10.45788Z","caller":"traceutil/trace.go:171","msg":"trace[768148931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"123.46922ms","start":"2026-08-05T21:37:10.3344Z","end":"2026-08-05T21:37:10.457869Z","steps":["trace[768148931] 'agreement among raft nodes before linearized reading' (duration: 123.283887ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:12.821074Z","caller":"traceutil/trace.go:171","msg":"trace[831430730] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"118.657008ms","start":"2026-08-05T21:37:12.70239Z","end":"2026-08-05T21:37:12.821047Z","steps":["trace[831430730] 'read index received' (duration: 18.766362ms)","trace[831430730] 'applied index is now lower than readState.Index' (duration: 99.889446ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:12.821217Z","caller":"traceutil/trace.go:171","msg":"trace[1777086149] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"134.673512ms","start":"2026-08-05T21:37:12.686528Z","end":"2026-08-05T21:37:12.821201Z","steps":["trace[1777086149] 'process raft request' (duration: 34.778036ms)","trace[1777086149] 'compare' (duration: 99.594191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:12.821444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T21:37:12.821534Z","caller":"traceutil/trace.go:171","msg":"trace[20786035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"118.920791ms","start":"2026-08-05T21:37:12.702594Z","end":"2026-08-05T21:37:12.821514Z","steps":["trace[20786035] 'agreement among raft nodes before linearized reading' (duration: 118.685967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:12.821731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.321138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:37:12.821787Z","caller":"traceutil/trace.go:171","msg":"trace[1129772736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"119.429919ms","start":"2026-08-05T21:37:12.702345Z","end":"2026-08-05T21:37:12.821775Z","steps":["trace[1129772736] 'agreement among raft nodes before linearized reading' (duration: 119.251156ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:12.95699Z","caller":"traceutil/trace.go:171","msg":"trace[803427730] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"131.53372ms","start":"2026-08-05T21:37:12.825426Z","end":"2026-08-05T21:37:12.956959Z","steps":["trace[803427730] 'process raft request' (duration: 114.831753ms)","trace[803427730] 'compare' (duration: 16.578815ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:14.342053Z","caller":"traceutil/trace.go:171","msg":"trace[1763776624] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"165.971627ms","start":"2026-08-05T21:37:14.176051Z","end":"2026-08-05T21:37:14.342023Z","steps":["trace[1763776624] 'process raft request' (duration: 162.082962ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:14.97745Z","caller":"traceutil/trace.go:171","msg":"trace[255251846] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"120.267329ms","start":"2026-08-05T21:37:14.857139Z","end":"2026-08-05T21:37:14.977406Z","steps":["trace[255251846] 'process raft request' (duration: 73.552337ms)","trace[255251846] 'compare' (duration: 46.399137ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:15.451468Z","caller":"traceutil/trace.go:171","msg":"trace[699037053] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"469.415603ms","start":"2026-08-05T21:37:14.982022Z","end":"2026-08-05T21:37:15.451437Z","steps":["trace[699037053] 'process raft request' (duration: 455.707986ms)","trace[699037053] 'compare' (duration: 13.400882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:15.451923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:14.982001Z","time spent":"469.84655ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:37:21.968757Z","caller":"traceutil/trace.go:171","msg":"trace[1255444906] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"108.514576ms","start":"2026-08-05T21:37:21.860212Z","end":"2026-08-05T21:37:21.968726Z","steps":["trace[1255444906] 'process raft request' (duration: 108.287532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:23.379788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.704303ms","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-05T21:37:23.379884Z","caller":"traceutil/trace.go:171","msg":"trace[439155329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"558.892106ms","start":"2026-08-05T21:37:22.820972Z","end":"2026-08-05T21:37:23.379864Z","steps":["trace[439155329] 'range keys from in-memory index tree' (duration: 558.49401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:23.37987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.276019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T21:37:23.379926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:22.820945Z","time spent":"558.970568ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T21:37:23.379993Z","caller":"traceutil/trace.go:171","msg":"trace[601621974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"349.392182ms","start":"2026-08-05T21:37:23.030528Z","end":"2026-08-05T21:37:23.379921Z","steps":["trace[601621974] 'range keys from in-memory index tree' (duration: 348.990885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:23.380112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:23.030509Z","time spent":"349.588655ms","remote":"127.0.0.1:52058","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-05T21:37:23.3802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.335925ms","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-05T21:37:23.380278Z","caller":"traceutil/trace.go:171","msg":"trace[2019510948] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1368; }","duration":"488.407416ms","start":"2026-08-05T21:37:22.89185Z","end":"2026-08-05T21:37:23.380257Z","steps":["trace[2019510948] 'range keys from in-memory index tree' (duration: 488.327475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:23.380568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.378144ms","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-05T21:37:23.380604Z","caller":"traceutil/trace.go:171","msg":"trace[1482625194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"466.444635ms","start":"2026-08-05T21:37:22.914147Z","end":"2026-08-05T21:37:23.380592Z","steps":["trace[1482625194] 'range keys from in-memory index tree' (duration: 466.265522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:23.380635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:22.914131Z","time spent":"466.491586ms","remote":"127.0.0.1:52058","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-05T21:37:45.677875Z","caller":"traceutil/trace.go:171","msg":"trace[187170722] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"105.720931ms","start":"2026-08-05T21:37:45.572119Z","end":"2026-08-05T21:37:45.67784Z","steps":["trace[187170722] 'read index received' (duration: 105.531408ms)","trace[187170722] 'applied index is now lower than readState.Index' (duration: 187.593µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:45.678149Z","caller":"traceutil/trace.go:171","msg":"trace[889646865] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"123.563708ms","start":"2026-08-05T21:37:45.55456Z","end":"2026-08-05T21:37:45.678124Z","steps":["trace[889646865] 'process raft request' (duration: 123.05388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:45.678942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.793658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-08-05T21:37:45.679035Z","caller":"traceutil/trace.go:171","msg":"trace[1160683208] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1447; }","duration":"106.97125ms","start":"2026-08-05T21:37:45.572044Z","end":"2026-08-05T21:37:45.679015Z","steps":["trace[1160683208] 'agreement among raft nodes before linearized reading' (duration: 106.129407ms)"],"step_count":1}