{"level":"warn","ts":"2026-05-29T21:56:53.296471Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:56:53.296619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T21:56:53.297864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:56:53.297888Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-29T21:56:53.297947Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:56:53.298817Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-29T21:56:53.298977Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T21:56:53.301656Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.402354ms"} {"level":"info","ts":"2026-05-29T21:56:53.309922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-29T21:56:53.310115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:56:53.310154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-29T21:56:53.310286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:56:53.310299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-29T21:56:53.311782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-29T21:56:53.317822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:56:53.322549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:56:53.324041Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T21:56:53.32848Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:56:53.328956Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:56:53.329053Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:56:53.329071Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:56:53.329349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:56:53.329996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-29T21:56:53.33012Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-29T21:56:53.333622Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:56:53.333834Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:56:53.33387Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:56:53.333908Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-29T21:56:53.333927Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-29T21:56:54.112127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:56:54.112192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:56:54.112216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-29T21:56:54.112238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:56:54.11225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-29T21:56:54.112268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-29T21:56:54.112289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-29T21:56:54.113703Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:56:54.115064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:56:54.115118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:56:54.113925Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:56:54.115812Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:56:54.115848Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:56:54.117171Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:56:54.117384Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:56:54.117511Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:56:54.118648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-29T21:56:54.118976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T21:56:58.700207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122898243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:56:59.857851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.886271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:56:59.857997Z","caller":"traceutil/trace.go:171","msg":"trace[128448227] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:262; }","duration":"1.657963554s","start":"2026-05-29T21:56:58.200013Z","end":"2026-05-29T21:56:59.857977Z","steps":["trace[128448227] 'read index received' (duration: 660.319858ms)","trace[128448227] 'applied index is now lower than readState.Index' (duration: 997.642356ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:59.858104Z","caller":"traceutil/trace.go:171","msg":"trace[432625045] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"1.658324611s","start":"2026-05-29T21:56:58.199769Z","end":"2026-05-29T21:56:59.858093Z","steps":["trace[432625045] 'process raft request' (duration: 660.618484ms)","trace[432625045] 'compare' (duration: 996.006146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:59.858164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.199747Z","time spent":"1.658390742s","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:56:59.858327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.369747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T21:56:59.858366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649426384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:59.858407Z","caller":"traceutil/trace.go:171","msg":"trace[1411415761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:257; }","duration":"134.53982ms","start":"2026-05-29T21:56:59.723846Z","end":"2026-05-29T21:56:59.858386Z","steps":["trace[1411415761] 'agreement among raft nodes before linearized reading' (duration: 134.346546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:59.858376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.831562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:56:59.858452Z","caller":"traceutil/trace.go:171","msg":"trace[863117961] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:257; }","duration":"1.649615648s","start":"2026-05-29T21:56:58.208819Z","end":"2026-05-29T21:56:59.858435Z","steps":["trace[863117961] 'agreement among raft nodes before linearized reading' (duration: 1.649346623s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:59.858515Z","caller":"traceutil/trace.go:171","msg":"trace[1380536537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:257; }","duration":"311.008765ms","start":"2026-05-29T21:56:59.54747Z","end":"2026-05-29T21:56:59.858478Z","steps":["trace[1380536537] 'agreement among raft nodes before linearized reading' (duration: 310.73509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:59.858532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.208813Z","time spent":"1.649707589s","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-29T21:56:59.85858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.547449Z","time spent":"311.121387ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:56:59.858375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658320761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:56:59.858726Z","caller":"traceutil/trace.go:171","msg":"trace[264788131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:257; }","duration":"1.658701408s","start":"2026-05-29T21:56:58.199983Z","end":"2026-05-29T21:56:59.858685Z","steps":["trace[264788131] 'agreement among raft nodes before linearized reading' (duration: 1.65823254s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:59.858779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.199972Z","time spent":"1.65879326s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:56:59.859024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532048037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-05-29T21:56:59.859133Z","caller":"traceutil/trace.go:171","msg":"trace[1553759246] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:257; }","duration":"1.53224159s","start":"2026-05-29T21:56:58.326862Z","end":"2026-05-29T21:56:59.859104Z","steps":["trace[1553759246] 'agreement among raft nodes before linearized reading' (duration: 1.531382063s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:59.859502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.326838Z","time spent":"1.532647188s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-29T21:57:00.364347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122898246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:57:01.099838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.281617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:57:01.099936Z","caller":"traceutil/trace.go:171","msg":"trace[2136272538] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"1.236066873s","start":"2026-05-29T21:56:59.863853Z","end":"2026-05-29T21:57:01.09992Z","steps":["trace[2136272538] 'process raft request' (duration: 574.47465ms)","trace[2136272538] 'compare' (duration: 661.065693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:01.099984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.863833Z","time spent":"1.236126643s","remote":"127.0.0.1:52210","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-05-29T21:57:01.423831Z","caller":"traceutil/trace.go:171","msg":"trace[507200228] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:263; }","duration":"1.559789216s","start":"2026-05-29T21:56:59.864019Z","end":"2026-05-29T21:57:01.423808Z","steps":["trace[507200228] 'read index received' (duration: 574.327147ms)","trace[507200228] 'applied index is now lower than readState.Index' (duration: 985.461069ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:01.423959Z","caller":"traceutil/trace.go:171","msg":"trace[250108496] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"1.5600583s","start":"2026-05-29T21:56:59.863889Z","end":"2026-05-29T21:57:01.423947Z","steps":["trace[250108496] 'process raft request' (duration: 1.554929955s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:01.424065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.863851Z","time spent":"1.560136342s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:01.424083Z","caller":"traceutil/trace.go:171","msg":"trace[186496456] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.555505155s","start":"2026-05-29T21:56:59.868552Z","end":"2026-05-29T21:57:01.424057Z","steps":["trace[186496456] 'process raft request' (duration: 1.55520231s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:01.42425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.868531Z","time spent":"1.555627278s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:01.424345Z","caller":"traceutil/trace.go:171","msg":"trace[548640622] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"1.559695384s","start":"2026-05-29T21:56:59.864552Z","end":"2026-05-29T21:57:01.424248Z","steps":["trace[548640622] 'process raft request' (duration: 1.559156593s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:01.42441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560377177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T21:57:01.424893Z","caller":"traceutil/trace.go:171","msg":"trace[1233268186] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"1.560552251s","start":"2026-05-29T21:56:59.863906Z","end":"2026-05-29T21:57:01.424458Z","steps":["trace[1233268186] 'agreement among raft nodes before linearized reading' (duration: 1.560418718s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:01.424987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.863884Z","time spent":"1.56107855s","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T21:57:01.425392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.864534Z","time spent":"1.559927709s","remote":"127.0.0.1:52210","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-05-29T21:57:01.425852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512663983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:01.425943Z","caller":"traceutil/trace.go:171","msg":"trace[839780540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:261; }","duration":"1.512846287s","start":"2026-05-29T21:56:59.913074Z","end":"2026-05-29T21:57:01.42592Z","steps":["trace[839780540] 'agreement among raft nodes before linearized reading' (duration: 1.51250099s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:01.42601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:59.913059Z","time spent":"1.512940318s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:57:25.688327Z","caller":"traceutil/trace.go:171","msg":"trace[690085689] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"125.856833ms","start":"2026-05-29T21:57:25.56245Z","end":"2026-05-29T21:57:25.688307Z","steps":["trace[690085689] 'process raft request' (duration: 125.763762ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:25.733395Z","caller":"traceutil/trace.go:171","msg":"trace[2011540900] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"144.246927ms","start":"2026-05-29T21:57:25.589133Z","end":"2026-05-29T21:57:25.733379Z","steps":["trace[2011540900] 'process raft request' (duration: 144.159664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:32.073844Z","caller":"traceutil/trace.go:171","msg":"trace[1440031918] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"277.882554ms","start":"2026-05-29T21:57:31.795935Z","end":"2026-05-29T21:57:32.073817Z","steps":["trace[1440031918] 'read index received' (duration: 277.64686ms)","trace[1440031918] 'applied index is now lower than readState.Index' (duration: 234.464µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:32.073982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.031148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T21:57:32.074081Z","caller":"traceutil/trace.go:171","msg":"trace[164158276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"278.17699ms","start":"2026-05-29T21:57:31.795883Z","end":"2026-05-29T21:57:32.07406Z","steps":["trace[164158276] 'agreement among raft nodes before linearized reading' (duration: 278.001176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:32.07408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.977616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:57:32.074126Z","caller":"traceutil/trace.go:171","msg":"trace[1923842904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"210.106269ms","start":"2026-05-29T21:57:31.864007Z","end":"2026-05-29T21:57:32.074113Z","steps":["trace[1923842904] 'agreement among raft nodes before linearized reading' (duration: 209.962606ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:32.074337Z","caller":"traceutil/trace.go:171","msg":"trace[1094670311] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"444.330856ms","start":"2026-05-29T21:57:31.629983Z","end":"2026-05-29T21:57:32.074314Z","steps":["trace[1094670311] 'process raft request' (duration: 443.674574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:32.074673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:31.629956Z","time spent":"444.632143ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:32.388245Z","caller":"traceutil/trace.go:171","msg":"trace[923510929] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"309.959705ms","start":"2026-05-29T21:57:32.078264Z","end":"2026-05-29T21:57:32.388224Z","steps":["trace[923510929] 'process raft request' (duration: 309.878244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:32.388408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.078229Z","time spent":"310.091958ms","remote":"127.0.0.1:52210","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-05-29T21:57:32.388536Z","caller":"traceutil/trace.go:171","msg":"trace[1635789290] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"310.940263ms","start":"2026-05-29T21:57:32.077587Z","end":"2026-05-29T21:57:32.388527Z","steps":["trace[1635789290] 'process raft request' (duration: 304.563943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:32.388592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.077566Z","time spent":"310.999895ms","remote":"127.0.0.1:52210","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-05-29T21:57:32.394031Z","caller":"traceutil/trace.go:171","msg":"trace[723578281] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:540; }","duration":"308.876675ms","start":"2026-05-29T21:57:32.085107Z","end":"2026-05-29T21:57:32.393984Z","steps":["trace[723578281] 'read index received' (duration: 296.998182ms)","trace[723578281] 'applied index is now lower than readState.Index' (duration: 11.876943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:32.39416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.028178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:32.394204Z","caller":"traceutil/trace.go:171","msg":"trace[153617375] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:525; }","duration":"309.11515ms","start":"2026-05-29T21:57:32.085076Z","end":"2026-05-29T21:57:32.394191Z","steps":["trace[153617375] 'agreement among raft nodes before linearized reading' (duration: 309.027578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:32.394254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.085066Z","time spent":"309.179111ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-29T21:57:33.208082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.567929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:57:33.208389Z","caller":"traceutil/trace.go:171","msg":"trace[1115903585] transaction","detail":"{read_only:false; number_of_response:0; response_revision:539; }","duration":"563.136298ms","start":"2026-05-29T21:57:32.645237Z","end":"2026-05-29T21:57:33.208373Z","steps":["trace[1115903585] 'process raft request' (duration: 563.106747ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:33.208413Z","caller":"traceutil/trace.go:171","msg":"trace[673327200] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"563.770909ms","start":"2026-05-29T21:57:32.644616Z","end":"2026-05-29T21:57:33.208387Z","steps":["trace[673327200] 'process raft request' (duration: 563.579455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.208459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.645225Z","time spent":"563.208729ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.208487Z","caller":"traceutil/trace.go:171","msg":"trace[315397478] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"563.887021ms","start":"2026-05-29T21:57:32.644575Z","end":"2026-05-29T21:57:33.208463Z","steps":["trace[315397478] 'process raft request' (duration: 354.838413ms)","trace[315397478] 'compare' (duration: 208.446787ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:33.208521Z","caller":"traceutil/trace.go:171","msg":"trace[1329388563] transaction","detail":"{read_only:false; number_of_response:0; response_revision:539; }","duration":"563.778129ms","start":"2026-05-29T21:57:32.644726Z","end":"2026-05-29T21:57:33.208504Z","steps":["trace[1329388563] 'process raft request' (duration: 563.594316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.208547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.644611Z","time spent":"563.85478ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:57:33.2086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.644722Z","time spent":"563.84139ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:57:33.208614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.644567Z","time spent":"563.982053ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:33.250717Z","caller":"traceutil/trace.go:171","msg":"trace[760539147] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"603.8739ms","start":"2026-05-29T21:57:32.646807Z","end":"2026-05-29T21:57:33.250681Z","steps":["trace[760539147] 'process raft request' (duration: 603.821439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.250827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.6468Z","time spent":"603.973022ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.251Z","caller":"traceutil/trace.go:171","msg":"trace[1973637305] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"605.609512ms","start":"2026-05-29T21:57:32.645383Z","end":"2026-05-29T21:57:33.250992Z","steps":["trace[1973637305] 'process raft request' (duration: 605.051961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.251049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.645378Z","time spent":"605.642063ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:33.251245Z","caller":"traceutil/trace.go:171","msg":"trace[364873438] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"600.034758ms","start":"2026-05-29T21:57:32.651203Z","end":"2026-05-29T21:57:33.251238Z","steps":["trace[364873438] 'process raft request' (duration: 599.729203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.251286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.651194Z","time spent":"600.073899ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.251301Z","caller":"traceutil/trace.go:171","msg":"trace[1083509034] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"597.393399ms","start":"2026-05-29T21:57:32.653879Z","end":"2026-05-29T21:57:33.251272Z","steps":["trace[1083509034] 'process raft request' (duration: 597.162285ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:33.254932Z","caller":"traceutil/trace.go:171","msg":"trace[1648507371] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"603.32078ms","start":"2026-05-29T21:57:32.651496Z","end":"2026-05-29T21:57:33.254817Z","steps":["trace[1648507371] 'process raft request' (duration: 599.524068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.255161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.651489Z","time spent":"603.618405ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.255307Z","caller":"traceutil/trace.go:171","msg":"trace[994955144] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"598.188263ms","start":"2026-05-29T21:57:32.656965Z","end":"2026-05-29T21:57:33.255153Z","steps":["trace[994955144] 'process raft request' (duration: 594.175527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.25541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.656955Z","time spent":"598.396627ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.255669Z","caller":"traceutil/trace.go:171","msg":"trace[1833924288] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"599.470117ms","start":"2026-05-29T21:57:32.656187Z","end":"2026-05-29T21:57:33.255657Z","steps":["trace[1833924288] 'process raft request' (duration: 594.888701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.255846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.656155Z","time spent":"599.538048ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:57:33.256195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.653873Z","time spent":"597.45537ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:33.256331Z","caller":"traceutil/trace.go:171","msg":"trace[1277813402] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"606.324476ms","start":"2026-05-29T21:57:32.649991Z","end":"2026-05-29T21:57:33.256315Z","steps":["trace[1277813402] 'process raft request' (duration: 600.66643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.256407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.649985Z","time spent":"606.376216ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7733,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:33.256608Z","caller":"traceutil/trace.go:171","msg":"trace[1579901084] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"597.614343ms","start":"2026-05-29T21:57:32.65874Z","end":"2026-05-29T21:57:33.256355Z","steps":["trace[1579901084] 'process raft request' (duration: 592.517478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.256662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.658731Z","time spent":"597.901618ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.256893Z","caller":"traceutil/trace.go:171","msg":"trace[1755275256] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"588.288478ms","start":"2026-05-29T21:57:32.668306Z","end":"2026-05-29T21:57:33.256595Z","steps":["trace[1755275256] 'process raft request' (duration: 583.328644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.26293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.668295Z","time spent":"588.623064ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:33.263352Z","caller":"traceutil/trace.go:171","msg":"trace[33929888] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:556; }","duration":"596.87194ms","start":"2026-05-29T21:57:32.666447Z","end":"2026-05-29T21:57:33.263319Z","steps":["trace[33929888] 'read index received' (duration: 333.075107ms)","trace[33929888] 'applied index is now lower than readState.Index' (duration: 263.796263ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:33.263501Z","caller":"traceutil/trace.go:171","msg":"trace[1147486933] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"474.781036ms","start":"2026-05-29T21:57:32.788711Z","end":"2026-05-29T21:57:33.263492Z","steps":["trace[1147486933] 'process raft request' (duration: 474.540441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.263548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.788679Z","time spent":"474.843077ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:57:33.263664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.215536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:33.263693Z","caller":"traceutil/trace.go:171","msg":"trace[701241946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"597.271136ms","start":"2026-05-29T21:57:32.666414Z","end":"2026-05-29T21:57:33.263685Z","steps":["trace[701241946] 'agreement among raft nodes before linearized reading' (duration: 597.211205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.263716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:32.666402Z","time spent":"597.309548ms","remote":"127.0.0.1:34026","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-05-29T21:57:33.264105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.446259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:33.264148Z","caller":"traceutil/trace.go:171","msg":"trace[286445368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"275.523311ms","start":"2026-05-29T21:57:32.988614Z","end":"2026-05-29T21:57:33.264137Z","steps":["trace[286445368] 'agreement among raft nodes before linearized reading' (duration: 275.44739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:33.836486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122898841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:57:34.336822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122898841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:57:34.550633Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.276968164s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:57:34.551769Z","caller":"traceutil/trace.go:171","msg":"trace[2129764422] transaction","detail":"{read_only:false; number_of_response:0; response_revision:551; }","duration":"1.278156196s","start":"2026-05-29T21:57:33.273593Z","end":"2026-05-29T21:57:34.551749Z","steps":["trace[2129764422] 'process raft request' (duration: 1.277278239s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.551887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.273577Z","time spent":"1.278242847s","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:34.746597Z","caller":"traceutil/trace.go:171","msg":"trace[467260672] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.468550835s","start":"2026-05-29T21:57:33.278013Z","end":"2026-05-29T21:57:34.746563Z","steps":["trace[467260672] 'process raft request' (duration: 1.468185738s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:34.74665Z","caller":"traceutil/trace.go:171","msg":"trace[1375658150] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.466350874s","start":"2026-05-29T21:57:33.280276Z","end":"2026-05-29T21:57:34.746627Z","steps":["trace[1375658150] 'process raft request' (duration: 1.46609682s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.746807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.280267Z","time spent":"1.466473186s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:57:34.746846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.277995Z","time spent":"1.468723069s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:34.746867Z","caller":"traceutil/trace.go:171","msg":"trace[208924750] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.451272722s","start":"2026-05-29T21:57:33.295557Z","end":"2026-05-29T21:57:34.74683Z","steps":["trace[208924750] 'process raft request' (duration: 1.451056578s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.295552Z","time spent":"1.451409994s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:34.747299Z","caller":"traceutil/trace.go:171","msg":"trace[12643577] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.44313025s","start":"2026-05-29T21:57:33.304133Z","end":"2026-05-29T21:57:34.747263Z","steps":["trace[12643577] 'process raft request' (duration: 1.44261739s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:34.747269Z","caller":"traceutil/trace.go:171","msg":"trace[65409692] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.451310272s","start":"2026-05-29T21:57:33.295472Z","end":"2026-05-29T21:57:34.746782Z","steps":["trace[65409692] 'process raft request' (duration: 1.450980336s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:34.747382Z","caller":"traceutil/trace.go:171","msg":"trace[1779886346] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:573; }","duration":"1.41154483s","start":"2026-05-29T21:57:33.33581Z","end":"2026-05-29T21:57:34.747354Z","steps":["trace[1779886346] 'read index received' (duration: 1.215133758s)","trace[1779886346] 'applied index is now lower than readState.Index' (duration: 196.409952ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:34.747436Z","caller":"traceutil/trace.go:171","msg":"trace[836173165] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.423588615s","start":"2026-05-29T21:57:33.323818Z","end":"2026-05-29T21:57:34.747406Z","steps":["trace[836173165] 'process raft request' (duration: 1.423319519s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.304118Z","time spent":"1.443265863s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:34.747482Z","caller":"traceutil/trace.go:171","msg":"trace[271458830] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.403956598s","start":"2026-05-29T21:57:33.343506Z","end":"2026-05-29T21:57:34.747463Z","steps":["trace[271458830] 'process raft request' (duration: 1.403710663s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.323775Z","time spent":"1.423696507s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:57:34.747569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411780685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:57:34.747638Z","caller":"traceutil/trace.go:171","msg":"trace[881576477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.411877356s","start":"2026-05-29T21:57:33.335743Z","end":"2026-05-29T21:57:34.747621Z","steps":["trace[881576477] 'agreement among raft nodes before linearized reading' (duration: 1.411737593s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.516807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:57:34.747697Z","caller":"traceutil/trace.go:171","msg":"trace[855174356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"354.58267ms","start":"2026-05-29T21:57:34.3931Z","end":"2026-05-29T21:57:34.747682Z","steps":["trace[855174356] 'agreement among raft nodes before linearized reading' (duration: 354.507999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.445946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T21:57:34.747735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.393078Z","time spent":"354.650351ms","remote":"127.0.0.1:52210","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-05-29T21:57:34.747751Z","caller":"traceutil/trace.go:171","msg":"trace[1463004924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"355.474206ms","start":"2026-05-29T21:57:34.392265Z","end":"2026-05-29T21:57:34.74774Z","steps":["trace[1463004924] 'agreement among raft nodes before linearized reading' (duration: 355.405955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.203891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T21:57:34.747785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.392223Z","time spent":"355.554928ms","remote":"127.0.0.1:52210","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-05-29T21:57:34.747723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.343479Z","time spent":"1.404050489s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:34.747818Z","caller":"traceutil/trace.go:171","msg":"trace[1257032650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"669.274192ms","start":"2026-05-29T21:57:34.078531Z","end":"2026-05-29T21:57:34.747806Z","steps":["trace[1257032650] 'agreement among raft nodes before linearized reading' (duration: 669.191971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.310173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"warn","ts":"2026-05-29T21:57:34.747693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.335729Z","time spent":"1.411952087s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:57:34.747876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.078521Z","time spent":"669.332383ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:57:34.74765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.021163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:34.747923Z","caller":"traceutil/trace.go:171","msg":"trace[607372208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"757.339409ms","start":"2026-05-29T21:57:33.990575Z","end":"2026-05-29T21:57:34.747914Z","steps":["trace[607372208] 'agreement among raft nodes before linearized reading' (duration: 757.029674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:34.747959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.990552Z","time spent":"757.40092ms","remote":"127.0.0.1:34026","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-05-29T21:57:34.748465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:33.295446Z","time spent":"1.452976883s","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:34.748493Z","caller":"traceutil/trace.go:171","msg":"trace[1798324328] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:558; }","duration":"194.423295ms","start":"2026-05-29T21:57:34.553437Z","end":"2026-05-29T21:57:34.74786Z","steps":["trace[1798324328] 'agreement among raft nodes before linearized reading' (duration: 194.173491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:35.11015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.774204Z","time spent":"335.93934ms","remote":"127.0.0.1:38838","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T21:57:35.110481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.253942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:57:35.110877Z","caller":"traceutil/trace.go:171","msg":"trace[788753435] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"344.653943ms","start":"2026-05-29T21:57:34.766203Z","end":"2026-05-29T21:57:35.110857Z","steps":["trace[788753435] 'process raft request' (duration: 344.48225ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.110683Z","caller":"traceutil/trace.go:171","msg":"trace[1234805970] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"345.53248ms","start":"2026-05-29T21:57:34.765116Z","end":"2026-05-29T21:57:35.110649Z","steps":["trace[1234805970] 'process raft request' (duration: 151.017714ms)","trace[1234805970] 'compare' (duration: 193.809413ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:35.11113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.766194Z","time spent":"344.833296ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:35.111214Z","caller":"traceutil/trace.go:171","msg":"trace[1504443492] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:583; }","duration":"120.049543ms","start":"2026-05-29T21:57:34.991144Z","end":"2026-05-29T21:57:35.111194Z","steps":["trace[1504443492] 'read index received' (duration: 119.30128ms)","trace[1504443492] 'applied index is now lower than readState.Index' (duration: 747.193µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:35.111275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.765093Z","time spent":"346.08191ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:57:35.111314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.161035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:35.111341Z","caller":"traceutil/trace.go:171","msg":"trace[2013236718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"120.225217ms","start":"2026-05-29T21:57:34.991107Z","end":"2026-05-29T21:57:35.111332Z","steps":["trace[2013236718] 'agreement among raft nodes before linearized reading' (duration: 120.154245ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.111658Z","caller":"traceutil/trace.go:171","msg":"trace[1358284038] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"323.255333ms","start":"2026-05-29T21:57:34.788392Z","end":"2026-05-29T21:57:35.111647Z","steps":["trace[1358284038] 'process raft request' (duration: 322.54855ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.111699Z","caller":"traceutil/trace.go:171","msg":"trace[1563463389] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"343.154975ms","start":"2026-05-29T21:57:34.768537Z","end":"2026-05-29T21:57:35.111692Z","steps":["trace[1563463389] 'process raft request' (duration: 342.305289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:35.11172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.788381Z","time spent":"323.311614ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:57:35.111763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.768522Z","time spent":"343.200796ms","remote":"127.0.0.1:52210","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-05-29T21:57:35.11185Z","caller":"traceutil/trace.go:171","msg":"trace[122160008] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"276.727174ms","start":"2026-05-29T21:57:34.835113Z","end":"2026-05-29T21:57:35.11184Z","steps":["trace[122160008] 'process raft request' (duration: 275.939019ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.111911Z","caller":"traceutil/trace.go:171","msg":"trace[1764842006] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"328.720346ms","start":"2026-05-29T21:57:34.78318Z","end":"2026-05-29T21:57:35.111901Z","steps":["trace[1764842006] 'process raft request' (duration: 327.709677ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.111663Z","caller":"traceutil/trace.go:171","msg":"trace[1600681924] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"344.103143ms","start":"2026-05-29T21:57:34.767547Z","end":"2026-05-29T21:57:35.11165Z","steps":["trace[1600681924] 'process raft request' (duration: 343.265127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:35.112001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.783154Z","time spent":"328.788157ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:57:35.11206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.76754Z","time spent":"344.47235ms","remote":"127.0.0.1:52210","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-05-29T21:57:35.112433Z","caller":"traceutil/trace.go:171","msg":"trace[2101445077] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"345.659983ms","start":"2026-05-29T21:57:34.765168Z","end":"2026-05-29T21:57:35.110828Z","steps":["trace[2101445077] 'process raft request' (duration: 345.422698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:35.112659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:34.765149Z","time spent":"347.468296ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:35.443238Z","caller":"traceutil/trace.go:171","msg":"trace[1829237536] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"169.938357ms","start":"2026-05-29T21:57:35.273275Z","end":"2026-05-29T21:57:35.443214Z","steps":["trace[1829237536] 'process raft request' (duration: 139.182873ms)","trace[1829237536] 'compare' (duration: 30.655962ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:35.474383Z","caller":"traceutil/trace.go:171","msg":"trace[73843882] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"196.881441ms","start":"2026-05-29T21:57:35.277486Z","end":"2026-05-29T21:57:35.474367Z","steps":["trace[73843882] 'process raft request' (duration: 196.79447ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:35.474626Z","caller":"traceutil/trace.go:171","msg":"trace[635869260] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"200.400186ms","start":"2026-05-29T21:57:35.274196Z","end":"2026-05-29T21:57:35.474596Z","steps":["trace[635869260] 'process raft request' (duration: 199.997919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.085688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.767084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:57:42.08578Z","caller":"traceutil/trace.go:171","msg":"trace[1179669568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"951.912297ms","start":"2026-05-29T21:57:41.133845Z","end":"2026-05-29T21:57:42.085758Z","steps":["trace[1179669568] 'range keys from in-memory index tree' (duration: 951.53838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.085822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:41.13382Z","time spent":"951.992238ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:57:42.086049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.080579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T21:57:42.086086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.810124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-29T21:57:42.086078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.147343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:57:42.086127Z","caller":"traceutil/trace.go:171","msg":"trace[1936461594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"944.862814ms","start":"2026-05-29T21:57:41.141253Z","end":"2026-05-29T21:57:42.086115Z","steps":["trace[1936461594] 'range keys from in-memory index tree' (duration: 944.654181ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:42.086135Z","caller":"traceutil/trace.go:171","msg":"trace[765923097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"945.19528ms","start":"2026-05-29T21:57:41.140917Z","end":"2026-05-29T21:57:42.086113Z","steps":["trace[765923097] 'range keys from in-memory index tree' (duration: 944.904796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.086165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:41.141245Z","time spent":"944.913915ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:57:42.086175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:41.140873Z","time spent":"945.293612ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:57:42.08618Z","caller":"traceutil/trace.go:171","msg":"trace[606951733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"943.288946ms","start":"2026-05-29T21:57:41.142838Z","end":"2026-05-29T21:57:42.086127Z","steps":["trace[606951733] 'range keys from in-memory index tree' (duration: 942.873758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.086267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:41.142819Z","time spent":"943.404827ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:57:42.132523Z","caller":"traceutil/trace.go:171","msg":"trace[1749731138] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"750.281347ms","start":"2026-05-29T21:57:41.38223Z","end":"2026-05-29T21:57:42.132511Z","steps":["trace[1749731138] 'process raft request' (duration: 749.543673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.132696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:41.382196Z","time spent":"750.392609ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:57:42.132685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.114096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:42.132783Z","caller":"traceutil/trace.go:171","msg":"trace[246740261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"142.261969ms","start":"2026-05-29T21:57:41.990497Z","end":"2026-05-29T21:57:42.132759Z","steps":["trace[246740261] 'agreement among raft nodes before linearized reading' (duration: 142.108506ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:42.132388Z","caller":"traceutil/trace.go:171","msg":"trace[1986675677] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"141.809911ms","start":"2026-05-29T21:57:41.990556Z","end":"2026-05-29T21:57:42.132366Z","steps":["trace[1986675677] 'read index received' (duration: 140.579998ms)","trace[1986675677] 'applied index is now lower than readState.Index' (duration: 1.229233ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:42.948768Z","caller":"traceutil/trace.go:171","msg":"trace[916043410] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"769.001036ms","start":"2026-05-29T21:57:42.179737Z","end":"2026-05-29T21:57:42.948738Z","steps":["trace[916043410] 'process raft request' (duration: 768.848683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:42.948925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:42.179708Z","time spent":"769.148689ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:43.072118Z","caller":"traceutil/trace.go:171","msg":"trace[126204073] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"402.457614ms","start":"2026-05-29T21:57:42.669637Z","end":"2026-05-29T21:57:43.072095Z","steps":["trace[126204073] 'read index received' (duration: 279.406243ms)","trace[126204073] 'applied index is now lower than readState.Index' (duration: 123.050331ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:43.072317Z","caller":"traceutil/trace.go:171","msg":"trace[564070789] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"298.112033ms","start":"2026-05-29T21:57:42.774162Z","end":"2026-05-29T21:57:43.072274Z","steps":["trace[564070789] 'process raft request' (duration: 297.818878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:44.316299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.940738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:57:44.316389Z","caller":"traceutil/trace.go:171","msg":"trace[368945954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"169.095471ms","start":"2026-05-29T21:57:44.147272Z","end":"2026-05-29T21:57:44.316368Z","steps":["trace[368945954] 'range keys from in-memory index tree' (duration: 168.815627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:44.316518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354872351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:44.316598Z","caller":"traceutil/trace.go:171","msg":"trace[594341356] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"1.354995192s","start":"2026-05-29T21:57:42.961576Z","end":"2026-05-29T21:57:44.316571Z","steps":["trace[594341356] 'agreement among raft nodes before linearized reading' (duration: 110.76122ms)","trace[594341356] 'range keys from in-memory index tree' (duration: 1.244133849s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:44.316642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:42.961556Z","time spent":"1.355076744s","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-29T21:57:44.316811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647191284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:44.316841Z","caller":"traceutil/trace.go:171","msg":"trace[846863031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.647283026s","start":"2026-05-29T21:57:42.669547Z","end":"2026-05-29T21:57:44.31683Z","steps":["trace[846863031] 'agreement among raft nodes before linearized reading' (duration: 402.80443ms)","trace[846863031] 'range keys from in-memory index tree' (duration: 1.244442785s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:44.316853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.187556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T21:57:44.316866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:42.669513Z","time spent":"1.647347287s","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:57:44.31689Z","caller":"traceutil/trace.go:171","msg":"trace[1202846042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"174.267358ms","start":"2026-05-29T21:57:44.142613Z","end":"2026-05-29T21:57:44.31688Z","steps":["trace[1202846042] 'range keys from in-memory index tree' (duration: 174.052274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:44.31701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32601727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:44.317043Z","caller":"traceutil/trace.go:171","msg":"trace[87731612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.326087531s","start":"2026-05-29T21:57:42.990943Z","end":"2026-05-29T21:57:44.317031Z","steps":["trace[87731612] 'agreement among raft nodes before linearized reading' (duration: 81.417402ms)","trace[87731612] 'range keys from in-memory index tree' (duration: 1.244633889s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:57:44.317069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:42.990917Z","time spent":"1.326147233s","remote":"127.0.0.1:34036","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-05-29T21:57:44.31729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.439592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:57:44.317331Z","caller":"traceutil/trace.go:171","msg":"trace[377156223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"174.499773ms","start":"2026-05-29T21:57:44.142821Z","end":"2026-05-29T21:57:44.317321Z","steps":["trace[377156223] 'range keys from in-memory index tree' (duration: 174.39307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:44.317507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.239119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:57:44.317593Z","caller":"traceutil/trace.go:171","msg":"trace[1169296075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"173.379621ms","start":"2026-05-29T21:57:44.14419Z","end":"2026-05-29T21:57:44.31757Z","steps":["trace[1169296075] 'range keys from in-memory index tree' (duration: 173.111307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:44.825407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122899055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:57:45.326184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122899055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:57:45.686414Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.364607421s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:57:45.686945Z","caller":"traceutil/trace.go:171","msg":"trace[1731218616] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.365179302s","start":"2026-05-29T21:57:44.32174Z","end":"2026-05-29T21:57:45.68692Z","steps":["trace[1731218616] 'process raft request' (duration: 1.365014089s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.687284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.321726Z","time spent":"1.365429587s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:45.982738Z","caller":"traceutil/trace.go:171","msg":"trace[1318597857] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.657960846s","start":"2026-05-29T21:57:44.324718Z","end":"2026-05-29T21:57:45.982679Z","steps":["trace[1318597857] 'process raft request' (duration: 1.656851455s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.983252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.324708Z","time spent":"1.658370254s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:45.982476Z","caller":"traceutil/trace.go:171","msg":"trace[812501383] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.658667049s","start":"2026-05-29T21:57:44.323736Z","end":"2026-05-29T21:57:45.982403Z","steps":["trace[812501383] 'process raft request' (duration: 1.657794363s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.984993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.323711Z","time spent":"1.660875881s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:45.985624Z","caller":"traceutil/trace.go:171","msg":"trace[1267593355] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.663456609s","start":"2026-05-29T21:57:44.322134Z","end":"2026-05-29T21:57:45.985591Z","steps":["trace[1267593355] 'process raft request' (duration: 1.659203249s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.985804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.322128Z","time spent":"1.663574121s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:45.991605Z","caller":"traceutil/trace.go:171","msg":"trace[45982750] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:657; }","duration":"1.666396104s","start":"2026-05-29T21:57:44.325166Z","end":"2026-05-29T21:57:45.991562Z","steps":["trace[45982750] 'read index received' (duration: 1.361503485s)","trace[45982750] 'applied index is now lower than readState.Index' (duration: 304.891689ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:45.991674Z","caller":"traceutil/trace.go:171","msg":"trace[803167502] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.28160549s","start":"2026-05-29T21:57:44.71004Z","end":"2026-05-29T21:57:45.991646Z","steps":["trace[803167502] 'process raft request' (duration: 1.2746641s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.99169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666512856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:45.991833Z","caller":"traceutil/trace.go:171","msg":"trace[1060569218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.66667926s","start":"2026-05-29T21:57:44.325144Z","end":"2026-05-29T21:57:45.991823Z","steps":["trace[1060569218] 'agreement among raft nodes before linearized reading' (duration: 1.666501046s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:45.991861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.325139Z","time spent":"1.666715061s","remote":"127.0.0.1:34026","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-05-29T21:57:45.9918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:44.710003Z","time spent":"1.281733722s","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:57:46.699232Z","caller":"traceutil/trace.go:171","msg":"trace[531107317] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"111.181119ms","start":"2026-05-29T21:57:46.588031Z","end":"2026-05-29T21:57:46.699212Z","steps":["trace[531107317] 'process raft request' (duration: 111.110428ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:46.699471Z","caller":"traceutil/trace.go:171","msg":"trace[1448670601] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"143.84711ms","start":"2026-05-29T21:57:46.555612Z","end":"2026-05-29T21:57:46.699459Z","steps":["trace[1448670601] 'process raft request' (duration: 137.95837ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:47.678485Z","caller":"traceutil/trace.go:171","msg":"trace[1054833868] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"497.127804ms","start":"2026-05-29T21:57:47.18133Z","end":"2026-05-29T21:57:47.678458Z","steps":["trace[1054833868] 'read index received' (duration: 495.927672ms)","trace[1054833868] 'applied index is now lower than readState.Index' (duration: 1.199212ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:47.678619Z","caller":"traceutil/trace.go:171","msg":"trace[1580553654] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"502.223149ms","start":"2026-05-29T21:57:47.176364Z","end":"2026-05-29T21:57:47.678587Z","steps":["trace[1580553654] 'process raft request' (duration: 500.896334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:47.678732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.358138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T21:57:47.678779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:47.176355Z","time spent":"502.338231ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:57:47.678789Z","caller":"traceutil/trace.go:171","msg":"trace[1571818123] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:647; }","duration":"497.500931ms","start":"2026-05-29T21:57:47.181275Z","end":"2026-05-29T21:57:47.678776Z","steps":["trace[1571818123] 'agreement among raft nodes before linearized reading' (duration: 497.282697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:47.678835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:47.18126Z","time spent":"497.566752ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-05-29T21:57:48.369273Z","caller":"traceutil/trace.go:171","msg":"trace[1612257036] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"225.354693ms","start":"2026-05-29T21:57:48.143891Z","end":"2026-05-29T21:57:48.369246Z","steps":["trace[1612257036] 'process raft request' (duration: 139.994307ms)","trace[1612257036] 'compare' (duration: 85.207533ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:48.369557Z","caller":"traceutil/trace.go:171","msg":"trace[1423215791] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"210.867173ms","start":"2026-05-29T21:57:48.158675Z","end":"2026-05-29T21:57:48.369542Z","steps":["trace[1423215791] 'process raft request' (duration: 210.532556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.258032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122899380,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:58:02.610364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619781442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:02.610485Z","caller":"traceutil/trace.go:171","msg":"trace[950832756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"1.619952695s","start":"2026-05-29T21:58:00.990505Z","end":"2026-05-29T21:58:02.610458Z","steps":["trace[950832756] 'range keys from in-memory index tree' (duration: 1.619700821s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.610543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:00.990485Z","time spent":"1.620044157s","remote":"127.0.0.1:34026","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-05-29T21:58:02.636479Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.472472477s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:58:02.637113Z","caller":"traceutil/trace.go:171","msg":"trace[196897411] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.473195181s","start":"2026-05-29T21:58:01.163882Z","end":"2026-05-29T21:58:02.637077Z","steps":["trace[196897411] 'process raft request' (duration: 1.472946036s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:02.637238Z","caller":"traceutil/trace.go:171","msg":"trace[1956646650] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"879.792488ms","start":"2026-05-29T21:58:01.757189Z","end":"2026-05-29T21:58:02.636982Z","steps":["trace[1956646650] 'read index received' (duration: 879.487713ms)","trace[1956646650] 'applied index is now lower than readState.Index' (duration: 303.865µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:02.637335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:01.163854Z","time spent":"1.473386405s","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:58:02.637772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.903735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:58:02.637807Z","caller":"traceutil/trace.go:171","msg":"trace[1543893390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"576.952376ms","start":"2026-05-29T21:58:02.060843Z","end":"2026-05-29T21:58:02.637795Z","steps":["trace[1543893390] 'agreement among raft nodes before linearized reading' (duration: 576.847474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.637837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:02.060831Z","time spent":"576.998428ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:58:02.63784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.852752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-29T21:58:02.637404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.215757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T21:58:02.637896Z","caller":"traceutil/trace.go:171","msg":"trace[2081726351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"585.941053ms","start":"2026-05-29T21:58:02.051932Z","end":"2026-05-29T21:58:02.637874Z","steps":["trace[2081726351] 'agreement among raft nodes before linearized reading' (duration: 585.75978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.637877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.455431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T21:58:02.63794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:02.051912Z","time spent":"586.021185ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:58:02.637959Z","caller":"traceutil/trace.go:171","msg":"trace[417252303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"588.590784ms","start":"2026-05-29T21:58:02.04935Z","end":"2026-05-29T21:58:02.63794Z","steps":["trace[417252303] 'agreement among raft nodes before linearized reading' (duration: 588.38119ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:02.637953Z","caller":"traceutil/trace.go:171","msg":"trace[1592811962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"880.795436ms","start":"2026-05-29T21:58:01.757132Z","end":"2026-05-29T21:58:02.637927Z","steps":["trace[1592811962] 'agreement among raft nodes before linearized reading' (duration: 880.157365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.638001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:02.049328Z","time spent":"588.665844ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:58:02.638021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:01.757102Z","time spent":"880.89927ms","remote":"127.0.0.1:52210","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-05-29T21:58:02.638892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.228278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:58:02.63896Z","caller":"traceutil/trace.go:171","msg":"trace[649749009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"656.351791ms","start":"2026-05-29T21:58:01.982593Z","end":"2026-05-29T21:58:02.638944Z","steps":["trace[649749009] 'agreement among raft nodes before linearized reading' (duration: 655.065596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:02.639114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:01.982572Z","time spent":"656.495524ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T21:58:02.858979Z","caller":"traceutil/trace.go:171","msg":"trace[346536881] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:830; }","duration":"212.021814ms","start":"2026-05-29T21:58:02.646932Z","end":"2026-05-29T21:58:02.858954Z","steps":["trace[346536881] 'read index received' (duration: 19.669638ms)","trace[346536881] 'applied index is now lower than readState.Index' (duration: 192.350785ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:02.858995Z","caller":"traceutil/trace.go:171","msg":"trace[1920243611] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"216.066929ms","start":"2026-05-29T21:58:02.642891Z","end":"2026-05-29T21:58:02.858958Z","steps":["trace[1920243611] 'process raft request' (duration: 177.62746ms)","trace[1920243611] 'compare' (duration: 38.133643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:02.859122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.164806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:02.859162Z","caller":"traceutil/trace.go:171","msg":"trace[1665115918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"212.238867ms","start":"2026-05-29T21:58:02.646912Z","end":"2026-05-29T21:58:02.859151Z","steps":["trace[1665115918] 'agreement among raft nodes before linearized reading' (duration: 212.141275ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:02.859227Z","caller":"traceutil/trace.go:171","msg":"trace[142946328] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"214.597001ms","start":"2026-05-29T21:58:02.64461Z","end":"2026-05-29T21:58:02.859207Z","steps":["trace[142946328] 'process raft request' (duration: 214.263025ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:02.859382Z","caller":"traceutil/trace.go:171","msg":"trace[1681416539] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"216.08554ms","start":"2026-05-29T21:58:02.643284Z","end":"2026-05-29T21:58:02.859369Z","steps":["trace[1681416539] 'process raft request' (duration: 215.547909ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:02.859452Z","caller":"traceutil/trace.go:171","msg":"trace[332160764] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"214.552871ms","start":"2026-05-29T21:58:02.644888Z","end":"2026-05-29T21:58:02.85944Z","steps":["trace[332160764] 'process raft request' (duration: 214.016031ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.463053Z","caller":"traceutil/trace.go:171","msg":"trace[2109007598] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"178.203082ms","start":"2026-05-29T21:58:09.284824Z","end":"2026-05-29T21:58:09.463027Z","steps":["trace[2109007598] 'process raft request' (duration: 178.044139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:09.64282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.790408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5066"} {"level":"info","ts":"2026-05-29T21:58:09.64299Z","caller":"traceutil/trace.go:171","msg":"trace[2146686294] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:876; }","duration":"158.954943ms","start":"2026-05-29T21:58:09.483957Z","end":"2026-05-29T21:58:09.642912Z","steps":["trace[2146686294] 'range keys from in-memory index tree' (duration: 158.585545ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.851098Z","caller":"traceutil/trace.go:171","msg":"trace[1448095543] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"148.570607ms","start":"2026-05-29T21:58:09.702504Z","end":"2026-05-29T21:58:09.851075Z","steps":["trace[1448095543] 'process raft request' (duration: 148.513406ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.851157Z","caller":"traceutil/trace.go:171","msg":"trace[878560707] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"152.134404ms","start":"2026-05-29T21:58:09.698989Z","end":"2026-05-29T21:58:09.851123Z","steps":["trace[878560707] 'process raft request' (duration: 151.89079ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:30.960102Z","caller":"traceutil/trace.go:171","msg":"trace[1240584000] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"123.525475ms","start":"2026-05-29T21:58:30.836548Z","end":"2026-05-29T21:58:30.960074Z","steps":["trace[1240584000] 'process raft request' (duration: 123.32195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:31.119194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.385477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:31.119287Z","caller":"traceutil/trace.go:171","msg":"trace[1162242297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"128.170601ms","start":"2026-05-29T21:58:30.991092Z","end":"2026-05-29T21:58:31.119263Z","steps":["trace[1162242297] 'range keys from in-memory index tree' (duration: 127.274155ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:37.280071Z","caller":"traceutil/trace.go:171","msg":"trace[1275048298] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"148.608664ms","start":"2026-05-29T21:58:37.13144Z","end":"2026-05-29T21:58:37.280049Z","steps":["trace[1275048298] 'read index received' (duration: 148.411731ms)","trace[1275048298] 'applied index is now lower than readState.Index' (duration: 195.873µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:37.280215Z","caller":"traceutil/trace.go:171","msg":"trace[1177300404] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"278.655461ms","start":"2026-05-29T21:58:37.001544Z","end":"2026-05-29T21:58:37.2802Z","steps":["trace[1177300404] 'process raft request' (duration: 278.368245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:37.280387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.933891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:37.280429Z","caller":"traceutil/trace.go:171","msg":"trace[84181092] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1015; }","duration":"149.052953ms","start":"2026-05-29T21:58:37.131365Z","end":"2026-05-29T21:58:37.280418Z","steps":["trace[84181092] 'agreement among raft nodes before linearized reading' (duration: 148.973281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:37.280721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.519049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:58:37.280801Z","caller":"traceutil/trace.go:171","msg":"trace[1834928574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"119.6387ms","start":"2026-05-29T21:58:37.161142Z","end":"2026-05-29T21:58:37.280781Z","steps":["trace[1834928574] 'agreement among raft nodes before linearized reading' (duration: 119.437317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:37.280882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.314008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:58:37.280921Z","caller":"traceutil/trace.go:171","msg":"trace[696381488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"101.382109ms","start":"2026-05-29T21:58:37.179526Z","end":"2026-05-29T21:58:37.280908Z","steps":["trace[696381488] 'agreement among raft nodes before linearized reading' (duration: 101.265147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:37.280936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.267517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T21:58:37.281014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.557721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:58:37.281009Z","caller":"traceutil/trace.go:171","msg":"trace[404587575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"101.397509ms","start":"2026-05-29T21:58:37.179591Z","end":"2026-05-29T21:58:37.280988Z","steps":["trace[404587575] 'agreement among raft nodes before linearized reading' (duration: 101.157895ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:37.281045Z","caller":"traceutil/trace.go:171","msg":"trace[2070760314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"101.612963ms","start":"2026-05-29T21:58:37.179423Z","end":"2026-05-29T21:58:37.281036Z","steps":["trace[2070760314] 'agreement among raft nodes before linearized reading' (duration: 101.526781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:37.52361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:58:37.523747Z","caller":"traceutil/trace.go:171","msg":"trace[1325187752] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"223.308105ms","start":"2026-05-29T21:58:37.300404Z","end":"2026-05-29T21:58:37.523712Z","steps":["trace[1325187752] 'process raft request' (duration: 86.41853ms)","trace[1325187752] 'compare' (duration: 136.538757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:37.835032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.448515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:58:37.835156Z","caller":"traceutil/trace.go:171","msg":"trace[2128324717] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"218.26804ms","start":"2026-05-29T21:58:37.616867Z","end":"2026-05-29T21:58:37.835135Z","steps":["trace[2128324717] 'process raft request' (duration: 32.595451ms)","trace[2128324717] 'compare' (duration: 185.283272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:40.947168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.236595516s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:58:40.947242Z","caller":"traceutil/trace.go:171","msg":"trace[679260510] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"1.957124539s","start":"2026-05-29T21:58:38.990105Z","end":"2026-05-29T21:58:40.94723Z","steps":["trace[679260510] 'read index received' (duration: 30.151µs)","trace[679260510] 'applied index is now lower than readState.Index' (duration: 1.957093258s)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:40.947291Z","caller":"traceutil/trace.go:171","msg":"trace[1718906451] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"3.099053165s","start":"2026-05-29T21:58:37.84823Z","end":"2026-05-29T21:58:40.947283Z","steps":["trace[1718906451] 'process raft request' (duration: 862.280996ms)","trace[1718906451] 'compare' (duration: 2.236347061s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:40.947326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:37.848207Z","time spent":"3.099100436s","remote":"127.0.0.1:52210","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-05-29T21:58:40.947381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.957261792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:40.947436Z","caller":"traceutil/trace.go:171","msg":"trace[1581014876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.957361124s","start":"2026-05-29T21:58:38.990062Z","end":"2026-05-29T21:58:40.947423Z","steps":["trace[1581014876] 'agreement among raft nodes before linearized reading' (duration: 1.957271912s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.947463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:38.990042Z","time spent":"1.957416244s","remote":"127.0.0.1:34026","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-05-29T21:58:40.951828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582594072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:58:40.951888Z","caller":"traceutil/trace.go:171","msg":"trace[1928068739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.582692453s","start":"2026-05-29T21:58:39.369182Z","end":"2026-05-29T21:58:40.951874Z","steps":["trace[1928068739] 'agreement among raft nodes before linearized reading' (duration: 1.58254447s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.951896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666093277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:58:40.951934Z","caller":"traceutil/trace.go:171","msg":"trace[1857758034] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.112425414s","start":"2026-05-29T21:58:39.839491Z","end":"2026-05-29T21:58:40.951916Z","steps":["trace[1857758034] 'process raft request' (duration: 1.112121818s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.951957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.997742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:40.951954Z","caller":"traceutil/trace.go:171","msg":"trace[684455948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"1.666204358s","start":"2026-05-29T21:58:39.285734Z","end":"2026-05-29T21:58:40.951938Z","steps":["trace[684455948] 'agreement among raft nodes before linearized reading' (duration: 1.666022345s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.95198Z","caller":"traceutil/trace.go:171","msg":"trace[1157262220] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1023; }","duration":"378.046123ms","start":"2026-05-29T21:58:40.573926Z","end":"2026-05-29T21:58:40.951972Z","steps":["trace[1157262220] 'agreement among raft nodes before linearized reading' (duration: 378.015152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.951987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.285714Z","time spent":"1.66626732s","remote":"127.0.0.1:52210","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-05-29T21:58:40.951999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:40.57391Z","time spent":"378.084364ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:58:40.952007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582809065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:58:40.952034Z","caller":"traceutil/trace.go:171","msg":"trace[1082213712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.582866477s","start":"2026-05-29T21:58:39.36916Z","end":"2026-05-29T21:58:40.952027Z","steps":["trace[1082213712] 'agreement among raft nodes before linearized reading' (duration: 1.582773835s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.952029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.839472Z","time spent":"1.112500405s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:58:40.952056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.369145Z","time spent":"1.582906977s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:58:40.952124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.659993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T21:58:40.952124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.443786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:40.95214Z","caller":"traceutil/trace.go:171","msg":"trace[171577878] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1023; }","duration":"885.708454ms","start":"2026-05-29T21:58:40.066426Z","end":"2026-05-29T21:58:40.952134Z","steps":["trace[171577878] 'agreement among raft nodes before linearized reading' (duration: 885.678864ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:40.952144Z","caller":"traceutil/trace.go:171","msg":"trace[651067495] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1023; }","duration":"168.463857ms","start":"2026-05-29T21:58:40.783675Z","end":"2026-05-29T21:58:40.952138Z","steps":["trace[651067495] 'agreement among raft nodes before linearized reading' (duration: 168.432866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.952157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:40.06641Z","time spent":"885.743035ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:58:40.951918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.369165Z","time spent":"1.582746484s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:58:40.951866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582552991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:58:40.952316Z","caller":"traceutil/trace.go:171","msg":"trace[1209040190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.58304613s","start":"2026-05-29T21:58:39.369263Z","end":"2026-05-29T21:58:40.952309Z","steps":["trace[1209040190] 'agreement among raft nodes before linearized reading' (duration: 1.582461119s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.952338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.369254Z","time spent":"1.58307722s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:58:40.952338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584348254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:58:40.952358Z","caller":"traceutil/trace.go:171","msg":"trace[1316986154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"1.584404755s","start":"2026-05-29T21:58:39.367948Z","end":"2026-05-29T21:58:40.952353Z","steps":["trace[1316986154] 'agreement among raft nodes before linearized reading' (duration: 1.584335824s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:58:40.952376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:39.367928Z","time spent":"1.584443686s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:58:43.242752Z","caller":"traceutil/trace.go:171","msg":"trace[861080425] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"199.292683ms","start":"2026-05-29T21:58:43.043397Z","end":"2026-05-29T21:58:43.24269Z","steps":["trace[861080425] 'process raft request' (duration: 140.694885ms)","trace[861080425] 'compare' (duration: 58.481676ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:43.243278Z","caller":"traceutil/trace.go:171","msg":"trace[1233544096] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"198.03531ms","start":"2026-05-29T21:58:43.045208Z","end":"2026-05-29T21:58:43.243243Z","steps":["trace[1233544096] 'process raft request' (duration: 197.870207ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:43.243555Z","caller":"traceutil/trace.go:171","msg":"trace[264644567] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"198.257845ms","start":"2026-05-29T21:58:43.04528Z","end":"2026-05-29T21:58:43.243537Z","steps":["trace[264644567] 'process raft request' (duration: 197.917247ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:43.243798Z","caller":"traceutil/trace.go:171","msg":"trace[710311458] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"198.436228ms","start":"2026-05-29T21:58:43.04535Z","end":"2026-05-29T21:58:43.243786Z","steps":["trace[710311458] 'process raft request' (duration: 198.107642ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:59.304437Z","caller":"traceutil/trace.go:171","msg":"trace[1135060029] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"196.95624ms","start":"2026-05-29T21:58:59.107456Z","end":"2026-05-29T21:58:59.304412Z","steps":["trace[1135060029] 'process raft request' (duration: 196.796638ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:59.44525Z","caller":"traceutil/trace.go:171","msg":"trace[1375272886] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"103.997318ms","start":"2026-05-29T21:58:59.34122Z","end":"2026-05-29T21:58:59.445217Z","steps":["trace[1375272886] 'process raft request' (duration: 103.923946ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:59.445367Z","caller":"traceutil/trace.go:171","msg":"trace[1006077327] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"103.209512ms","start":"2026-05-29T21:58:59.342142Z","end":"2026-05-29T21:58:59.445352Z","steps":["trace[1006077327] 'process raft request' (duration: 103.047219ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:59.445288Z","caller":"traceutil/trace.go:171","msg":"trace[775733811] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"104.613139ms","start":"2026-05-29T21:58:59.340648Z","end":"2026-05-29T21:58:59.445261Z","steps":["trace[775733811] 'process raft request' (duration: 96.585829ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:01.185903Z","caller":"traceutil/trace.go:171","msg":"trace[410409087] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"131.778239ms","start":"2026-05-29T21:59:01.054096Z","end":"2026-05-29T21:59:01.185874Z","steps":["trace[410409087] 'process raft request' (duration: 131.606566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.927627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.534026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T21:59:01.927711Z","caller":"traceutil/trace.go:171","msg":"trace[2057620143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"478.664849ms","start":"2026-05-29T21:59:01.449027Z","end":"2026-05-29T21:59:01.927692Z","steps":["trace[2057620143] 'range keys from in-memory index tree' (duration: 478.382924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.927752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.449021Z","time spent":"478.723589ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:59:01.928036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.423185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:01.928113Z","caller":"traceutil/trace.go:171","msg":"trace[771091261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"619.553968ms","start":"2026-05-29T21:59:01.308541Z","end":"2026-05-29T21:59:01.928095Z","steps":["trace[771091261] 'range keys from in-memory index tree' (duration: 619.243132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.928082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.067217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:59:01.928213Z","caller":"traceutil/trace.go:171","msg":"trace[1534866712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"510.23377ms","start":"2026-05-29T21:59:01.417964Z","end":"2026-05-29T21:59:01.928198Z","steps":["trace[1534866712] 'range keys from in-memory index tree' (duration: 509.944624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.928198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.668277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T21:59:01.928282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.417945Z","time spent":"510.324912ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:59:01.928316Z","caller":"traceutil/trace.go:171","msg":"trace[1701361781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"479.83255ms","start":"2026-05-29T21:59:01.448461Z","end":"2026-05-29T21:59:01.928293Z","steps":["trace[1701361781] 'range keys from in-memory index tree' (duration: 479.494584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.928608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.448442Z","time spent":"480.151826ms","remote":"127.0.0.1:52210","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-05-29T21:59:01.928159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.308525Z","time spent":"619.62012ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:01.950861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900049,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:59:01.983337Z","caller":"traceutil/trace.go:171","msg":"trace[1582078463] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"533.642829ms","start":"2026-05-29T21:59:01.449653Z","end":"2026-05-29T21:59:01.983295Z","steps":["trace[1582078463] 'process raft request' (duration: 533.521577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:01.983527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.449638Z","time spent":"533.762411ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:02.000317Z","caller":"traceutil/trace.go:171","msg":"trace[1689973276] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1134; }","duration":"549.928944ms","start":"2026-05-29T21:59:01.450367Z","end":"2026-05-29T21:59:02.000295Z","steps":["trace[1689973276] 'read index received' (duration: 532.914876ms)","trace[1689973276] 'applied index is now lower than readState.Index' (duration: 17.013068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:02.000664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.245662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:02.000717Z","caller":"traceutil/trace.go:171","msg":"trace[1061710411] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1093; }","duration":"318.304083ms","start":"2026-05-29T21:59:01.682401Z","end":"2026-05-29T21:59:02.000705Z","steps":["trace[1061710411] 'agreement among raft nodes before linearized reading' (duration: 318.222472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:02.0007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.28218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T21:59:02.000752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.68233Z","time spent":"318.413545ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:59:02.000787Z","caller":"traceutil/trace.go:171","msg":"trace[571622814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"550.412252ms","start":"2026-05-29T21:59:01.450352Z","end":"2026-05-29T21:59:02.000764Z","steps":["trace[571622814] 'agreement among raft nodes before linearized reading' (duration: 550.177088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:02.001054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:01.450344Z","time spent":"550.481433ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:02.178284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.024931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:59:02.178674Z","caller":"traceutil/trace.go:171","msg":"trace[824259292] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"173.354348ms","start":"2026-05-29T21:59:02.005298Z","end":"2026-05-29T21:59:02.178653Z","steps":["trace[824259292] 'process raft request' (duration: 54.841107ms)","trace[824259292] 'compare' (duration: 117.91533ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:02.635657Z","caller":"traceutil/trace.go:171","msg":"trace[1673065158] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"109.89969ms","start":"2026-05-29T21:59:02.525725Z","end":"2026-05-29T21:59:02.635625Z","steps":["trace[1673065158] 'read index received' (duration: 15.874229ms)","trace[1673065158] 'applied index is now lower than readState.Index' (duration: 94.024051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:02.635813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.047362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:02.635857Z","caller":"traceutil/trace.go:171","msg":"trace[1757921801] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1094; }","duration":"110.135004ms","start":"2026-05-29T21:59:02.525711Z","end":"2026-05-29T21:59:02.635846Z","steps":["trace[1757921801] 'agreement among raft nodes before linearized reading' (duration: 110.018942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.1078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:59:04.210366Z","caller":"traceutil/trace.go:171","msg":"trace[671230906] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.019154046s","start":"2026-05-29T21:59:03.191188Z","end":"2026-05-29T21:59:04.210342Z","steps":["trace[671230906] 'process raft request' (duration: 1.018807049s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.210566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:03.191165Z","time spent":"1.019321148s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:04.406375Z","caller":"traceutil/trace.go:171","msg":"trace[2088445376] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1140; }","duration":"798.978711ms","start":"2026-05-29T21:59:03.607344Z","end":"2026-05-29T21:59:04.406323Z","steps":["trace[2088445376] 'read index received' (duration: 602.742973ms)","trace[2088445376] 'applied index is now lower than readState.Index' (duration: 196.234708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:04.406757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.449693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:59:04.40678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.424279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:59:04.406852Z","caller":"traceutil/trace.go:171","msg":"trace[60112689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"416.601986ms","start":"2026-05-29T21:59:03.990191Z","end":"2026-05-29T21:59:04.406793Z","steps":["trace[60112689] 'agreement among raft nodes before linearized reading' (duration: 416.461444ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:04.406893Z","caller":"traceutil/trace.go:171","msg":"trace[1439605780] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"393.452342ms","start":"2026-05-29T21:59:04.01343Z","end":"2026-05-29T21:59:04.406882Z","steps":["trace[1439605780] 'process raft request' (duration: 391.109168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.406919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:03.990171Z","time spent":"416.738019ms","remote":"127.0.0.1:34026","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-05-29T21:59:04.406973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.013414Z","time spent":"393.506123ms","remote":"127.0.0.1:52210","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-05-29T21:59:04.407348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.333373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:59:04.407384Z","caller":"traceutil/trace.go:171","msg":"trace[1174270305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"225.433054ms","start":"2026-05-29T21:59:04.181941Z","end":"2026-05-29T21:59:04.407374Z","steps":["trace[1174270305] 'agreement among raft nodes before linearized reading' (duration: 225.260801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.407463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.46533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:59:04.407499Z","caller":"traceutil/trace.go:171","msg":"trace[1489775430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"402.526392ms","start":"2026-05-29T21:59:04.004962Z","end":"2026-05-29T21:59:04.407489Z","steps":["trace[1489775430] 'agreement among raft nodes before linearized reading' (duration: 402.299158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.407528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.004951Z","time spent":"402.571343ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:04.407568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.576932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T21:59:04.407624Z","caller":"traceutil/trace.go:171","msg":"trace[1101288930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"402.659334ms","start":"2026-05-29T21:59:04.004955Z","end":"2026-05-29T21:59:04.407615Z","steps":["trace[1101288930] 'agreement among raft nodes before linearized reading' (duration: 402.526082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.407665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.392509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T21:59:04.407678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.004935Z","time spent":"402.708435ms","remote":"127.0.0.1:52210","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":"info","ts":"2026-05-29T21:59:04.407691Z","caller":"traceutil/trace.go:171","msg":"trace[106055705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"402.434502ms","start":"2026-05-29T21:59:04.005248Z","end":"2026-05-29T21:59:04.407683Z","steps":["trace[106055705] 'agreement among raft nodes before linearized reading' (duration: 402.358149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.407716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.00524Z","time spent":"402.470972ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:59:04.407859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.787628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:59:04.407893Z","caller":"traceutil/trace.go:171","msg":"trace[801403712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"402.833198ms","start":"2026-05-29T21:59:04.005042Z","end":"2026-05-29T21:59:04.407875Z","steps":["trace[801403712] 'agreement among raft nodes before linearized reading' (duration: 402.727916ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:04.40685Z","caller":"traceutil/trace.go:171","msg":"trace[947025675] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1096; }","duration":"799.550181ms","start":"2026-05-29T21:59:03.607281Z","end":"2026-05-29T21:59:04.406831Z","steps":["trace[947025675] 'agreement among raft nodes before linearized reading' (duration: 799.344907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.407919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.005Z","time spent":"402.91306ms","remote":"127.0.0.1:52210","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-05-29T21:59:04.408005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:03.607254Z","time spent":"800.709144ms","remote":"127.0.0.1:52512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:59:04.817349Z","caller":"traceutil/trace.go:171","msg":"trace[529390341] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"403.103123ms","start":"2026-05-29T21:59:04.414227Z","end":"2026-05-29T21:59:04.81733Z","steps":["trace[529390341] 'process raft request' (duration: 403.075672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.817601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.414219Z","time spent":"403.319327ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:04.817373Z","caller":"traceutil/trace.go:171","msg":"trace[1362138574] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"404.481778ms","start":"2026-05-29T21:59:04.412868Z","end":"2026-05-29T21:59:04.81735Z","steps":["trace[1362138574] 'process raft request' (duration: 404.384207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.817865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.41285Z","time spent":"404.947036ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:04.817371Z","caller":"traceutil/trace.go:171","msg":"trace[861373813] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"404.847735ms","start":"2026-05-29T21:59:04.412461Z","end":"2026-05-29T21:59:04.817309Z","steps":["trace[861373813] 'process raft request' (duration: 404.685782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.818059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.412452Z","time spent":"405.57829ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:04.817421Z","caller":"traceutil/trace.go:171","msg":"trace[1332110104] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"403.406648ms","start":"2026-05-29T21:59:04.413985Z","end":"2026-05-29T21:59:04.817392Z","steps":["trace[1332110104] 'process raft request' (duration: 403.294936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:04.818193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.413977Z","time spent":"404.174043ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:05.273977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.986724ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:05.274112Z","caller":"traceutil/trace.go:171","msg":"trace[1481190891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1101; }","duration":"226.147227ms","start":"2026-05-29T21:59:05.047945Z","end":"2026-05-29T21:59:05.274092Z","steps":["trace[1481190891] 'range keys from in-memory index tree' (duration: 225.965843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:05.274087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.41643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:59:05.274644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.592463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:59:05.274689Z","caller":"traceutil/trace.go:171","msg":"trace[42056458] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1101; }","duration":"331.646844ms","start":"2026-05-29T21:59:04.94303Z","end":"2026-05-29T21:59:05.274677Z","steps":["trace[42056458] 'count revisions from in-memory index tree' (duration: 331.479081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:05.274915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:04.942957Z","time spent":"331.942009ms","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:59:05.274604Z","caller":"traceutil/trace.go:171","msg":"trace[568600925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"283.984061ms","start":"2026-05-29T21:59:04.990601Z","end":"2026-05-29T21:59:05.274586Z","steps":["trace[568600925] 'range keys from in-memory index tree' (duration: 283.305118ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:06.592579Z","caller":"traceutil/trace.go:171","msg":"trace[595823207] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"101.747107ms","start":"2026-05-29T21:59:06.490803Z","end":"2026-05-29T21:59:06.59255Z","steps":["trace[595823207] 'process raft request' (duration: 101.624654ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:07.019546Z","caller":"traceutil/trace.go:171","msg":"trace[1521028705] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1149; }","duration":"173.159554ms","start":"2026-05-29T21:59:06.846365Z","end":"2026-05-29T21:59:07.019524Z","steps":["trace[1521028705] 'read index received' (duration: 20.605646ms)","trace[1521028705] 'applied index is now lower than readState.Index' (duration: 152.552858ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:07.019632Z","caller":"traceutil/trace.go:171","msg":"trace[618183300] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"193.011277ms","start":"2026-05-29T21:59:06.8266Z","end":"2026-05-29T21:59:07.019612Z","steps":["trace[618183300] 'process raft request' (duration: 192.887765ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:07.019642Z","caller":"traceutil/trace.go:171","msg":"trace[1458340562] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"193.046837ms","start":"2026-05-29T21:59:06.826557Z","end":"2026-05-29T21:59:07.019604Z","steps":["trace[1458340562] 'process raft request' (duration: 192.892424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:07.019704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.310298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:07.01974Z","caller":"traceutil/trace.go:171","msg":"trace[1949473797] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1107; }","duration":"173.390619ms","start":"2026-05-29T21:59:06.84634Z","end":"2026-05-29T21:59:07.01973Z","steps":["trace[1949473797] 'agreement among raft nodes before linearized reading' (duration: 173.306798ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:07.019803Z","caller":"traceutil/trace.go:171","msg":"trace[2106937382] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"193.277481ms","start":"2026-05-29T21:59:06.826476Z","end":"2026-05-29T21:59:07.019753Z","steps":["trace[2106937382] 'process raft request' (duration: 118.130113ms)","trace[2106937382] 'compare' (duration: 74.729351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:07.337172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.602592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T21:59:07.337254Z","caller":"traceutil/trace.go:171","msg":"trace[1960276719] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1107; }","duration":"150.729844ms","start":"2026-05-29T21:59:07.186506Z","end":"2026-05-29T21:59:07.337236Z","steps":["trace[1960276719] 'range keys from in-memory index tree' (duration: 150.407679ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:28.967274Z","caller":"traceutil/trace.go:171","msg":"trace[1505421106] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"480.880689ms","start":"2026-05-29T21:59:28.486374Z","end":"2026-05-29T21:59:28.967255Z","steps":["trace[1505421106] 'read index received' (duration: 480.662905ms)","trace[1505421106] 'applied index is now lower than readState.Index' (duration: 216.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:28.967424Z","caller":"traceutil/trace.go:171","msg":"trace[20088575] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"655.838568ms","start":"2026-05-29T21:59:28.31155Z","end":"2026-05-29T21:59:28.967389Z","steps":["trace[20088575] 'process raft request' (duration: 655.571663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:28.967517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.4748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T21:59:28.967509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.102694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:28.967555Z","caller":"traceutil/trace.go:171","msg":"trace[1608136886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"260.553961ms","start":"2026-05-29T21:59:28.70699Z","end":"2026-05-29T21:59:28.967544Z","steps":["trace[1608136886] 'agreement among raft nodes before linearized reading' (duration: 260.430169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:28.967578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:28.31152Z","time spent":"655.97492ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:28.967615Z","caller":"traceutil/trace.go:171","msg":"trace[347915449] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1226; }","duration":"481.251527ms","start":"2026-05-29T21:59:28.486321Z","end":"2026-05-29T21:59:28.967572Z","steps":["trace[347915449] 'agreement among raft nodes before linearized reading' (duration: 481.080944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:28.967656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:28.486299Z","time spent":"481.346538ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:59:29.49001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:59:30.010059Z","caller":"traceutil/trace.go:171","msg":"trace[1538578433] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.038504217s","start":"2026-05-29T21:59:28.971538Z","end":"2026-05-29T21:59:30.010042Z","steps":["trace[1538578433] 'process raft request' (duration: 980.669014ms)","trace[1538578433] 'compare' (duration: 57.163401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:30.010163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:28.971518Z","time spent":"1.03858827s","remote":"127.0.0.1:52210","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-05-29T21:59:30.010642Z","caller":"traceutil/trace.go:171","msg":"trace[1496768842] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"1.038731212s","start":"2026-05-29T21:59:28.971879Z","end":"2026-05-29T21:59:30.010611Z","steps":["trace[1496768842] 'process raft request' (duration: 1.038097939s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.011077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:28.971854Z","time spent":"1.039129839s","remote":"127.0.0.1:52210","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-05-29T21:59:30.010712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02095178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:30.011357Z","caller":"traceutil/trace.go:171","msg":"trace[420304259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"1.021608531s","start":"2026-05-29T21:59:28.989709Z","end":"2026-05-29T21:59:30.011318Z","steps":["trace[420304259] 'agreement among raft nodes before linearized reading' (duration: 1.02095735s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.011504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:28.989696Z","time spent":"1.021781255s","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:59:30.013188Z","caller":"traceutil/trace.go:171","msg":"trace[419576369] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1279; }","duration":"1.023420766s","start":"2026-05-29T21:59:28.989748Z","end":"2026-05-29T21:59:30.013169Z","steps":["trace[419576369] 'read index received' (duration: 962.475734ms)","trace[419576369] 'applied index is now lower than readState.Index' (duration: 58.293263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:30.013457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.009768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:30.013494Z","caller":"traceutil/trace.go:171","msg":"trace[844329858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1228; }","duration":"865.090618ms","start":"2026-05-29T21:59:29.148393Z","end":"2026-05-29T21:59:30.013484Z","steps":["trace[844329858] 'agreement among raft nodes before linearized reading' (duration: 865.017597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.013526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:29.148373Z","time spent":"865.1446ms","remote":"127.0.0.1:38910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:59:30.01384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.493317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:59:30.013859Z","caller":"traceutil/trace.go:171","msg":"trace[50318642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"857.543888ms","start":"2026-05-29T21:59:29.15631Z","end":"2026-05-29T21:59:30.013854Z","steps":["trace[50318642] 'agreement among raft nodes before linearized reading' (duration: 857.458466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.013875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:29.1563Z","time spent":"857.572188ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:30.013988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.920044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:59:30.014005Z","caller":"traceutil/trace.go:171","msg":"trace[1157931176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"857.954755ms","start":"2026-05-29T21:59:29.156045Z","end":"2026-05-29T21:59:30.014Z","steps":["trace[1157931176] 'agreement among raft nodes before linearized reading' (duration: 857.903784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.01402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:29.156036Z","time spent":"857.981346ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:30.014121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.441543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:30.014137Z","caller":"traceutil/trace.go:171","msg":"trace[1244394973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"859.457173ms","start":"2026-05-29T21:59:29.154675Z","end":"2026-05-29T21:59:30.014132Z","steps":["trace[1244394973] 'agreement among raft nodes before linearized reading' (duration: 859.414002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:30.014152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:29.154654Z","time spent":"859.494733ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:42.578573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:59:43.044581Z","caller":"traceutil/trace.go:171","msg":"trace[511946055] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"968.600538ms","start":"2026-05-29T21:59:42.075953Z","end":"2026-05-29T21:59:43.044554Z","steps":["trace[511946055] 'process raft request' (duration: 968.444325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.044749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.075938Z","time spent":"968.72231ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:43.048305Z","caller":"traceutil/trace.go:171","msg":"trace[47316360] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"361.30827ms","start":"2026-05-29T21:59:42.68698Z","end":"2026-05-29T21:59:43.048288Z","steps":["trace[47316360] 'process raft request' (duration: 361.184597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.048396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.686952Z","time spent":"361.401502ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:43.048631Z","caller":"traceutil/trace.go:171","msg":"trace[2020989538] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"970.30695ms","start":"2026-05-29T21:59:42.078295Z","end":"2026-05-29T21:59:43.048602Z","steps":["trace[2020989538] 'read index received' (duration: 966.087621ms)","trace[2020989538] 'applied index is now lower than readState.Index' (duration: 4.217789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:43.048914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.331363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:43.048963Z","caller":"traceutil/trace.go:171","msg":"trace[1937268800] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1268; }","duration":"486.422525ms","start":"2026-05-29T21:59:42.562528Z","end":"2026-05-29T21:59:43.048951Z","steps":["trace[1937268800] 'agreement among raft nodes before linearized reading' (duration: 486.350113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.049001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.562507Z","time spent":"486.486075ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:59:43.049121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.272614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:43.049191Z","caller":"traceutil/trace.go:171","msg":"trace[1292533811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1268; }","duration":"966.541739ms","start":"2026-05-29T21:59:42.08264Z","end":"2026-05-29T21:59:43.049181Z","steps":["trace[1292533811] 'agreement among raft nodes before linearized reading' (duration: 966.191732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.049217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.799425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:59:43.049276Z","caller":"traceutil/trace.go:171","msg":"trace[1734263243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"965.865187ms","start":"2026-05-29T21:59:42.083392Z","end":"2026-05-29T21:59:43.049258Z","steps":["trace[1734263243] 'agreement among raft nodes before linearized reading' (duration: 965.771885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.049318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.083382Z","time spent":"965.928258ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:43.049296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.082627Z","time spent":"966.619631ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:43.049108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.961983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-29T21:59:43.049067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.708117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:59:43.049559Z","caller":"traceutil/trace.go:171","msg":"trace[1132534480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"971.267277ms","start":"2026-05-29T21:59:42.078274Z","end":"2026-05-29T21:59:43.049541Z","steps":["trace[1132534480] 'agreement among raft nodes before linearized reading' (duration: 970.442092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.04961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.078267Z","time spent":"971.329108ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:59:43.04947Z","caller":"traceutil/trace.go:171","msg":"trace[502591207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"963.36225ms","start":"2026-05-29T21:59:42.086087Z","end":"2026-05-29T21:59:43.049449Z","steps":["trace[502591207] 'agreement among raft nodes before linearized reading' (duration: 962.891751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:43.049699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:42.08607Z","time spent":"963.611955ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T21:59:47.508033Z","caller":"traceutil/trace.go:171","msg":"trace[2127823116] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"447.145988ms","start":"2026-05-29T21:59:47.060852Z","end":"2026-05-29T21:59:47.507998Z","steps":["trace[2127823116] 'process raft request' (duration: 446.927683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:47.508255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.060814Z","time spent":"447.321982ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:48.003843Z","caller":"traceutil/trace.go:171","msg":"trace[534976614] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"937.18092ms","start":"2026-05-29T21:59:47.06664Z","end":"2026-05-29T21:59:48.003821Z","steps":["trace[534976614] 'read index received' (duration: 441.160896ms)","trace[534976614] 'applied index is now lower than readState.Index' (duration: 496.018904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:48.004133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.54566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:59:48.004162Z","caller":"traceutil/trace.go:171","msg":"trace[1454025645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"934.601722ms","start":"2026-05-29T21:59:47.069554Z","end":"2026-05-29T21:59:48.004156Z","steps":["trace[1454025645] 'agreement among raft nodes before linearized reading' (duration: 934.51207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:48.004191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.069546Z","time spent":"934.640882ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T21:59:48.00429Z","caller":"traceutil/trace.go:171","msg":"trace[1484329281] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"940.075713ms","start":"2026-05-29T21:59:47.064207Z","end":"2026-05-29T21:59:48.004283Z","steps":["trace[1484329281] 'process raft request' (duration: 903.897075ms)","trace[1484329281] 'compare' (duration: 35.609578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:48.004346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.064172Z","time spent":"940.135384ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:48.004402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.528809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T21:59:48.00445Z","caller":"traceutil/trace.go:171","msg":"trace[695925102] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1301; }","duration":"815.613531ms","start":"2026-05-29T21:59:47.188826Z","end":"2026-05-29T21:59:48.004439Z","steps":["trace[695925102] 'agreement among raft nodes before linearized reading' (duration: 815.493339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:48.004486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.188812Z","time spent":"815.666992ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-05-29T21:59:48.004523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.224844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:48.004539Z","caller":"traceutil/trace.go:171","msg":"trace[207732880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"933.270696ms","start":"2026-05-29T21:59:47.071263Z","end":"2026-05-29T21:59:48.004534Z","steps":["trace[207732880] 'agreement among raft nodes before linearized reading' (duration: 933.226705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:48.004517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.271196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-29T21:59:48.004565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.071218Z","time spent":"933.342937ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:59:48.004587Z","caller":"traceutil/trace.go:171","msg":"trace[51036214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"933.392317ms","start":"2026-05-29T21:59:47.071175Z","end":"2026-05-29T21:59:48.004568Z","steps":["trace[51036214] 'agreement among raft nodes before linearized reading' (duration: 933.156163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:48.004573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.909903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-29T21:59:48.00464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.071152Z","time spent":"933.479009ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:59:48.00467Z","caller":"traceutil/trace.go:171","msg":"trace[2137578693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"938.035716ms","start":"2026-05-29T21:59:47.066612Z","end":"2026-05-29T21:59:48.004647Z","steps":["trace[2137578693] 'agreement among raft nodes before linearized reading' (duration: 937.751301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:48.004733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:47.066601Z","time spent":"938.119937ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:59:48.195683Z","caller":"traceutil/trace.go:171","msg":"trace[1415273729] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"185.15153ms","start":"2026-05-29T21:59:48.010507Z","end":"2026-05-29T21:59:48.195659Z","steps":["trace[1415273729] 'process raft request' (duration: 185.089229ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:48.195798Z","caller":"traceutil/trace.go:171","msg":"trace[1378320423] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"187.210268ms","start":"2026-05-29T21:59:48.008555Z","end":"2026-05-29T21:59:48.195765Z","steps":["trace[1378320423] 'process raft request' (duration: 144.050709ms)","trace[1378320423] 'compare' (duration: 42.775631ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:48.195921Z","caller":"traceutil/trace.go:171","msg":"trace[1065855488] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"187.277859ms","start":"2026-05-29T21:59:48.008633Z","end":"2026-05-29T21:59:48.195911Z","steps":["trace[1065855488] 'process raft request' (duration: 186.891302ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:48.196049Z","caller":"traceutil/trace.go:171","msg":"trace[61944633] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"187.274929ms","start":"2026-05-29T21:59:48.008765Z","end":"2026-05-29T21:59:48.19604Z","steps":["trace[61944633] 'process raft request' (duration: 186.79964ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:48.214528Z","caller":"traceutil/trace.go:171","msg":"trace[394684351] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"142.824556ms","start":"2026-05-29T21:59:48.071685Z","end":"2026-05-29T21:59:48.214509Z","steps":["trace[394684351] 'process raft request' (duration: 142.682753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.491766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:59:50.839328Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.324860373s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:59:50.83972Z","caller":"traceutil/trace.go:171","msg":"trace[1130611354] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"848.416186ms","start":"2026-05-29T21:59:49.991282Z","end":"2026-05-29T21:59:50.839698Z","steps":["trace[1130611354] 'read index received' (duration: 848.227822ms)","trace[1130611354] 'applied index is now lower than readState.Index' (duration: 187.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:50.839985Z","caller":"traceutil/trace.go:171","msg":"trace[2038278052] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"1.325662658s","start":"2026-05-29T21:59:49.514309Z","end":"2026-05-29T21:59:50.839972Z","steps":["trace[2038278052] 'process raft request' (duration: 1.325272711s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.840111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:49.514274Z","time spent":"1.32574978s","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:50.840355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.111339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:50.840402Z","caller":"traceutil/trace.go:171","msg":"trace[1047041402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"849.199701ms","start":"2026-05-29T21:59:49.99119Z","end":"2026-05-29T21:59:50.84039Z","steps":["trace[1047041402] 'agreement among raft nodes before linearized reading' (duration: 849.13369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.84046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:49.991139Z","time spent":"849.310413ms","remote":"127.0.0.1:34026","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-05-29T21:59:50.840633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.470258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T21:59:50.840687Z","caller":"traceutil/trace.go:171","msg":"trace[1465545726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"831.57875ms","start":"2026-05-29T21:59:50.009098Z","end":"2026-05-29T21:59:50.840677Z","steps":["trace[1465545726] 'agreement among raft nodes before linearized reading' (duration: 831.414267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.840714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.009078Z","time spent":"831.631651ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:59:50.840872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.865579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:59:50.84089Z","caller":"traceutil/trace.go:171","msg":"trace[909982134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"639.903579ms","start":"2026-05-29T21:59:50.200981Z","end":"2026-05-29T21:59:50.840885Z","steps":["trace[909982134] 'agreement among raft nodes before linearized reading' (duration: 639.848378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.840902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.200974Z","time spent":"639.925509ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:50.84099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.437739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:59:50.841006Z","caller":"traceutil/trace.go:171","msg":"trace[905971853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"640.469309ms","start":"2026-05-29T21:59:50.200532Z","end":"2026-05-29T21:59:50.841002Z","steps":["trace[905971853] 'agreement among raft nodes before linearized reading' (duration: 640.428419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.84102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.200524Z","time spent":"640.49209ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:50.841112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.044991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:59:50.84114Z","caller":"traceutil/trace.go:171","msg":"trace[754804162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"641.095042ms","start":"2026-05-29T21:59:50.200041Z","end":"2026-05-29T21:59:50.841136Z","steps":["trace[754804162] 'agreement among raft nodes before linearized reading' (duration: 641.043411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.841152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.200031Z","time spent":"641.117742ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:59:50.841237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.438898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:50.841293Z","caller":"traceutil/trace.go:171","msg":"trace[722275390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"641.532199ms","start":"2026-05-29T21:59:50.199747Z","end":"2026-05-29T21:59:50.841279Z","steps":["trace[722275390] 'agreement among raft nodes before linearized reading' (duration: 641.306685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:50.84134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.199728Z","time spent":"641.601021ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:59:51.278776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.856744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:59:51.279093Z","caller":"traceutil/trace.go:171","msg":"trace[327989516] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"433.179445ms","start":"2026-05-29T21:59:50.845894Z","end":"2026-05-29T21:59:51.279073Z","steps":["trace[327989516] 'process raft request' (duration: 433.100374ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:51.279119Z","caller":"traceutil/trace.go:171","msg":"trace[842801489] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"433.767727ms","start":"2026-05-29T21:59:50.845316Z","end":"2026-05-29T21:59:51.279084Z","steps":["trace[842801489] 'process raft request' (duration: 433.555884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:51.279166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.845877Z","time spent":"433.252717ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:51.279181Z","caller":"traceutil/trace.go:171","msg":"trace[372328379] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"433.833869ms","start":"2026-05-29T21:59:50.845324Z","end":"2026-05-29T21:59:51.279158Z","steps":["trace[372328379] 'process raft request' (duration: 74.459115ms)","trace[372328379] 'compare' (duration: 358.631489ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:51.279237Z","caller":"traceutil/trace.go:171","msg":"trace[193754153] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"433.820268ms","start":"2026-05-29T21:59:50.845394Z","end":"2026-05-29T21:59:51.279215Z","steps":["trace[193754153] 'process raft request' (duration: 433.571533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:51.279295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.845308Z","time spent":"433.91562ms","remote":"127.0.0.1:52210","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-05-29T21:59:51.279295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.845277Z","time spent":"433.92803ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:51.27936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.84539Z","time spent":"433.898149ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:51.279689Z","caller":"traceutil/trace.go:171","msg":"trace[936219573] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"434.309567ms","start":"2026-05-29T21:59:50.845357Z","end":"2026-05-29T21:59:51.279667Z","steps":["trace[936219573] 'process raft request' (duration: 433.578564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:51.279802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:50.845347Z","time spent":"434.390058ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:52.401037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699577122900549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:59:52.474575Z","caller":"traceutil/trace.go:171","msg":"trace[87152063] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"573.950864ms","start":"2026-05-29T21:59:51.900602Z","end":"2026-05-29T21:59:52.474553Z","steps":["trace[87152063] 'read index received' (duration: 573.74358ms)","trace[87152063] 'applied index is now lower than readState.Index' (duration: 206.394µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:52.474732Z","caller":"traceutil/trace.go:171","msg":"trace[1534896005] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"653.209279ms","start":"2026-05-29T21:59:51.821472Z","end":"2026-05-29T21:59:52.474681Z","steps":["trace[1534896005] 'process raft request' (duration: 652.962945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:52.474839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:51.821447Z","time spent":"653.325881ms","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:59:52.47506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.245984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:59:52.475084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.459083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:52.475134Z","caller":"traceutil/trace.go:171","msg":"trace[374355226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"484.370775ms","start":"2026-05-29T21:59:51.990745Z","end":"2026-05-29T21:59:52.475116Z","steps":["trace[374355226] 'agreement among raft nodes before linearized reading' (duration: 484.208652ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:52.475148Z","caller":"traceutil/trace.go:171","msg":"trace[526449239] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1314; }","duration":"574.609725ms","start":"2026-05-29T21:59:51.900518Z","end":"2026-05-29T21:59:52.475128Z","steps":["trace[526449239] 'agreement among raft nodes before linearized reading' (duration: 574.446782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:52.475175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:51.990725Z","time spent":"484.443137ms","remote":"127.0.0.1:34026","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-05-29T21:59:52.475196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:51.900501Z","time spent":"574.687147ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-29T21:59:52.585828Z","caller":"traceutil/trace.go:171","msg":"trace[1639882966] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"102.801086ms","start":"2026-05-29T21:59:52.482994Z","end":"2026-05-29T21:59:52.585795Z","steps":["trace[1639882966] 'process raft request' (duration: 70.270987ms)","trace[1639882966] 'compare' (duration: 32.442967ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:53.474206Z","caller":"traceutil/trace.go:171","msg":"trace[1192474001] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"190.54331ms","start":"2026-05-29T21:59:53.28364Z","end":"2026-05-29T21:59:53.474183Z","steps":["trace[1192474001] 'read index received' (duration: 190.303306ms)","trace[1192474001] 'applied index is now lower than readState.Index' (duration: 239.204µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:53.474674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.025009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T21:59:53.474754Z","caller":"traceutil/trace.go:171","msg":"trace[1374622519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"191.11456ms","start":"2026-05-29T21:59:53.283624Z","end":"2026-05-29T21:59:53.474739Z","steps":["trace[1374622519] 'agreement among raft nodes before linearized reading' (duration: 190.937207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.474839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.943207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T21:59:53.47491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.487729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T21:59:53.475003Z","caller":"traceutil/trace.go:171","msg":"trace[2125350624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"190.618022ms","start":"2026-05-29T21:59:53.284364Z","end":"2026-05-29T21:59:53.474982Z","steps":["trace[2125350624] 'agreement among raft nodes before linearized reading' (duration: 190.261585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.47503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.389625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:59:53.474953Z","caller":"traceutil/trace.go:171","msg":"trace[368108601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"191.07919ms","start":"2026-05-29T21:59:53.283849Z","end":"2026-05-29T21:59:53.474928Z","steps":["trace[368108601] 'agreement among raft nodes before linearized reading' (duration: 190.706453ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:53.475073Z","caller":"traceutil/trace.go:171","msg":"trace[239604619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"191.434026ms","start":"2026-05-29T21:59:53.283628Z","end":"2026-05-29T21:59:53.475062Z","steps":["trace[239604619] 'agreement among raft nodes before linearized reading' (duration: 190.865206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.475143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.485937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:59:53.47523Z","caller":"traceutil/trace.go:171","msg":"trace[82368237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"191.62475ms","start":"2026-05-29T21:59:53.283587Z","end":"2026-05-29T21:59:53.475212Z","steps":["trace[82368237] 'agreement among raft nodes before linearized reading' (duration: 191.355445ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:53.474926Z","caller":"traceutil/trace.go:171","msg":"trace[909093883] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"387.254525ms","start":"2026-05-29T21:59:53.087527Z","end":"2026-05-29T21:59:53.474782Z","steps":["trace[909093883] 'process raft request' (duration: 386.523982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.475475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.0875Z","time spent":"387.873098ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:53.786405Z","caller":"traceutil/trace.go:171","msg":"trace[1779167079] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"305.181758ms","start":"2026-05-29T21:59:53.481193Z","end":"2026-05-29T21:59:53.786374Z","steps":["trace[1779167079] 'process raft request' (duration: 221.331396ms)","trace[1779167079] 'compare' (duration: 83.660388ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:53.786767Z","caller":"traceutil/trace.go:171","msg":"trace[1874284697] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"305.406172ms","start":"2026-05-29T21:59:53.481329Z","end":"2026-05-29T21:59:53.786736Z","steps":["trace[1874284697] 'process raft request' (duration: 305.000435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.786972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.481321Z","time spent":"305.536784ms","remote":"127.0.0.1:52210","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-05-29T21:59:53.787221Z","caller":"traceutil/trace.go:171","msg":"trace[1050701433] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"305.695687ms","start":"2026-05-29T21:59:53.481492Z","end":"2026-05-29T21:59:53.787188Z","steps":["trace[1050701433] 'process raft request' (duration: 305.101416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.787434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.481161Z","time spent":"305.347021ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:59:53.787512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.481484Z","time spent":"305.921451ms","remote":"127.0.0.1:52210","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-05-29T21:59:53.787947Z","caller":"traceutil/trace.go:171","msg":"trace[1187567531] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"306.200747ms","start":"2026-05-29T21:59:53.481719Z","end":"2026-05-29T21:59:53.78792Z","steps":["trace[1187567531] 'process raft request' (duration: 305.343821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:53.788229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.481686Z","time spent":"306.443932ms","remote":"127.0.0.1:52210","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-05-29T21:59:54.343129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.912023ms","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":"warn","ts":"2026-05-29T21:59:54.343153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.934911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:54.343203Z","caller":"traceutil/trace.go:171","msg":"trace[537295414] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1326; }","duration":"170.039375ms","start":"2026-05-29T21:59:54.17315Z","end":"2026-05-29T21:59:54.343189Z","steps":["trace[537295414] 'count revisions from in-memory index tree' (duration: 169.813852ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:54.343231Z","caller":"traceutil/trace.go:171","msg":"trace[668967810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"354.076774ms","start":"2026-05-29T21:59:53.989134Z","end":"2026-05-29T21:59:54.343211Z","steps":["trace[668967810] 'range keys from in-memory index tree' (duration: 353.825349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:54.343277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:53.989108Z","time spent":"354.161416ms","remote":"127.0.0.1:34036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:59:55.343002Z","caller":"traceutil/trace.go:171","msg":"trace[2050833073] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"352.29976ms","start":"2026-05-29T21:59:54.990676Z","end":"2026-05-29T21:59:55.342976Z","steps":["trace[2050833073] 'read index received' (duration: 352.182188ms)","trace[2050833073] 'applied index is now lower than readState.Index' (duration: 116.392µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:55.343045Z","caller":"traceutil/trace.go:171","msg":"trace[1341650349] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"489.657704ms","start":"2026-05-29T21:59:54.853361Z","end":"2026-05-29T21:59:55.343018Z","steps":["trace[1341650349] 'process raft request' (duration: 489.45912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:55.343128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.431853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:55.343161Z","caller":"traceutil/trace.go:171","msg":"trace[1625612564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"352.540865ms","start":"2026-05-29T21:59:54.99061Z","end":"2026-05-29T21:59:55.343151Z","steps":["trace[1625612564] 'agreement among raft nodes before linearized reading' (duration: 352.465483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:55.3432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:54.990594Z","time spent":"352.598967ms","remote":"127.0.0.1:34026","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-05-29T21:59:55.343185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:54.853336Z","time spent":"489.772067ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:59:55.540059Z","caller":"traceutil/trace.go:171","msg":"trace[2001541938] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"195.656906ms","start":"2026-05-29T21:59:55.344375Z","end":"2026-05-29T21:59:55.540032Z","steps":["trace[2001541938] 'read index received' (duration: 102.763286ms)","trace[2001541938] 'applied index is now lower than readState.Index' (duration: 92.89228ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:59:55.54037Z","caller":"traceutil/trace.go:171","msg":"trace[566366818] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"332.631612ms","start":"2026-05-29T21:59:55.207685Z","end":"2026-05-29T21:59:55.540317Z","steps":["trace[566366818] 'process raft request' (duration: 239.522778ms)","trace[566366818] 'compare' (duration: 92.3002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:59:55.540442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.041703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:55.540526Z","caller":"traceutil/trace.go:171","msg":"trace[856480314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"196.166566ms","start":"2026-05-29T21:59:55.344339Z","end":"2026-05-29T21:59:55.540506Z","steps":["trace[856480314] 'agreement among raft nodes before linearized reading' (duration: 195.981312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:55.540517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.259024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-29T21:59:55.540612Z","caller":"traceutil/trace.go:171","msg":"trace[332905205] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1328; }","duration":"143.416948ms","start":"2026-05-29T21:59:55.397176Z","end":"2026-05-29T21:59:55.540593Z","steps":["trace[332905205] 'agreement among raft nodes before linearized reading' (duration: 143.199123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:55.540605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:59:55.207661Z","time spent":"332.846136ms","remote":"127.0.0.1:52210","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-05-29T21:59:55.54059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.130299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125736"} {"level":"info","ts":"2026-05-29T21:59:55.540677Z","caller":"traceutil/trace.go:171","msg":"trace[1803785242] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1328; }","duration":"146.257231ms","start":"2026-05-29T21:59:55.394407Z","end":"2026-05-29T21:59:55.540665Z","steps":["trace[1803785242] 'agreement among raft nodes before linearized reading' (duration: 145.931194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:59:57.137169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.529993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:59:57.137255Z","caller":"traceutil/trace.go:171","msg":"trace[112042652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"148.784058ms","start":"2026-05-29T21:59:56.98845Z","end":"2026-05-29T21:59:57.137234Z","steps":["trace[112042652] 'range keys from in-memory index tree' (duration: 148.432952ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:59:59.983072Z","caller":"traceutil/trace.go:171","msg":"trace[1031296247] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"139.927642ms","start":"2026-05-29T21:59:59.843122Z","end":"2026-05-29T21:59:59.98305Z","steps":["trace[1031296247] 'process raft request' (duration: 139.768639ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:03.540363Z","caller":"traceutil/trace.go:171","msg":"trace[1681724843] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"173.561342ms","start":"2026-05-29T22:00:03.366769Z","end":"2026-05-29T22:00:03.54033Z","steps":["trace[1681724843] 'process raft request' (duration: 173.364859ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:37.895297Z","caller":"traceutil/trace.go:171","msg":"trace[484272800] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"106.000761ms","start":"2026-05-29T22:00:37.789263Z","end":"2026-05-29T22:00:37.895264Z","steps":["trace[484272800] 'process raft request' (duration: 30.279999ms)","trace[484272800] 'compare' (duration: 75.596139ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:40.494156Z","caller":"traceutil/trace.go:171","msg":"trace[1756384066] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1629; }","duration":"132.939066ms","start":"2026-05-29T22:00:40.361196Z","end":"2026-05-29T22:00:40.494135Z","steps":["trace[1756384066] 'read index received' (duration: 123.135852ms)","trace[1756384066] 'applied index is now lower than readState.Index' (duration: 9.802374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:40.49436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.14539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:40.494557Z","caller":"traceutil/trace.go:171","msg":"trace[868604969] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1564; }","duration":"133.356083ms","start":"2026-05-29T22:00:40.361189Z","end":"2026-05-29T22:00:40.494545Z","steps":["trace[868604969] 'agreement among raft nodes before linearized reading' (duration: 133.114789ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:40.494239Z","caller":"traceutil/trace.go:171","msg":"trace[1089860588] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"185.639205ms","start":"2026-05-29T22:00:40.308555Z","end":"2026-05-29T22:00:40.494194Z","steps":["trace[1089860588] 'process raft request' (duration: 185.534233ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:40.494233Z","caller":"traceutil/trace.go:171","msg":"trace[844333696] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"187.490661ms","start":"2026-05-29T22:00:40.306676Z","end":"2026-05-29T22:00:40.494167Z","steps":["trace[844333696] 'process raft request' (duration: 177.722467ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:06.626486Z","caller":"traceutil/trace.go:171","msg":"trace[618799018] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"100.953965ms","start":"2026-05-29T22:01:06.525485Z","end":"2026-05-29T22:01:06.626439Z","steps":["trace[618799018] 'process raft request' (duration: 81.119343ms)","trace[618799018] 'compare' (duration: 19.71745ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:35.597642Z","caller":"traceutil/trace.go:171","msg":"trace[1035591270] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"216.997574ms","start":"2026-05-29T22:01:35.380616Z","end":"2026-05-29T22:01:35.597614Z","steps":["trace[1035591270] 'process raft request' (duration: 216.848371ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:37.959863Z","caller":"traceutil/trace.go:171","msg":"trace[1737020043] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"131.494009ms","start":"2026-05-29T22:01:37.828288Z","end":"2026-05-29T22:01:37.959782Z","steps":["trace[1737020043] 'process raft request' (duration: 131.367537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:52.818471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.811532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T22:01:52.818672Z","caller":"traceutil/trace.go:171","msg":"trace[1908163566] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"126.988251ms","start":"2026-05-29T22:01:52.691664Z","end":"2026-05-29T22:01:52.818653Z","steps":["trace[1908163566] 'read index received' (duration: 13.058856ms)","trace[1908163566] 'applied index is now lower than readState.Index' (duration: 113.927615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:52.818914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.228465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-29T22:01:52.818993Z","caller":"traceutil/trace.go:171","msg":"trace[1533351219] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1889; }","duration":"127.351378ms","start":"2026-05-29T22:01:52.691621Z","end":"2026-05-29T22:01:52.818972Z","steps":["trace[1533351219] 'agreement among raft nodes before linearized reading' (duration: 127.125644ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:43.887488Z","caller":"traceutil/trace.go:171","msg":"trace[1323267472] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2524; }","duration":"163.083201ms","start":"2026-05-29T22:03:43.724383Z","end":"2026-05-29T22:03:43.887466Z","steps":["trace[1323267472] 'read index received' (duration: 162.878767ms)","trace[1323267472] 'applied index is now lower than readState.Index' (duration: 203.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:43.887609Z","caller":"traceutil/trace.go:171","msg":"trace[1481931762] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"340.38656ms","start":"2026-05-29T22:03:43.547187Z","end":"2026-05-29T22:03:43.887573Z","steps":["trace[1481931762] 'process raft request' (duration: 340.141575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:43.887715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.317685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-29T22:03:43.887785Z","caller":"traceutil/trace.go:171","msg":"trace[1788212480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2420; }","duration":"163.446417ms","start":"2026-05-29T22:03:43.724326Z","end":"2026-05-29T22:03:43.887772Z","steps":["trace[1788212480] 'agreement among raft nodes before linearized reading' (duration: 163.284954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:43.88786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:43.547175Z","time spent":"340.535832ms","remote":"127.0.0.1:52210","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-05-29T22:03:49.422629Z","caller":"traceutil/trace.go:171","msg":"trace[166732380] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"107.346901ms","start":"2026-05-29T22:03:49.315248Z","end":"2026-05-29T22:03:49.422595Z","steps":["trace[166732380] 'process raft request' (duration: 66.690184ms)","trace[166732380] 'compare' (duration: 40.534085ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:50.260695Z","caller":"traceutil/trace.go:171","msg":"trace[352620083] linearizableReadLoop","detail":"{readStateIndex:2560; appliedIndex:2559; }","duration":"341.525885ms","start":"2026-05-29T22:03:49.919148Z","end":"2026-05-29T22:03:50.260674Z","steps":["trace[352620083] 'read index received' (duration: 341.381112ms)","trace[352620083] 'applied index is now lower than readState.Index' (duration: 143.673µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:50.260799Z","caller":"traceutil/trace.go:171","msg":"trace[1302793694] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"344.297528ms","start":"2026-05-29T22:03:49.916491Z","end":"2026-05-29T22:03:50.260789Z","steps":["trace[1302793694] 'process raft request' (duration: 344.047093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:50.260908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:49.916467Z","time spent":"344.357269ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:50.260952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.79339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-29T22:03:50.261004Z","caller":"traceutil/trace.go:171","msg":"trace[2013030687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2454; }","duration":"341.898972ms","start":"2026-05-29T22:03:49.919091Z","end":"2026-05-29T22:03:50.26099Z","steps":["trace[2013030687] 'agreement among raft nodes before linearized reading' (duration: 341.744749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:50.261045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:49.919065Z","time spent":"341.972143ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T22:03:50.284741Z","caller":"traceutil/trace.go:171","msg":"trace[976098668] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"113.486168ms","start":"2026-05-29T22:03:50.171236Z","end":"2026-05-29T22:03:50.284722Z","steps":["trace[976098668] 'process raft request' (duration: 113.352056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:50.364371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.172161ms","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-05-29T22:03:50.36439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.956715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-29T22:03:50.36443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.653837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T22:03:50.364477Z","caller":"traceutil/trace.go:171","msg":"trace[1957672464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2455; }","duration":"427.307593ms","start":"2026-05-29T22:03:49.93712Z","end":"2026-05-29T22:03:50.364427Z","steps":["trace[1957672464] 'agreement among raft nodes before linearized reading' (duration: 347.894145ms)","trace[1957672464] 'range keys from in-memory index tree' (duration: 79.212864ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:50.364491Z","caller":"traceutil/trace.go:171","msg":"trace[1560987516] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2455; }","duration":"441.089187ms","start":"2026-05-29T22:03:49.923378Z","end":"2026-05-29T22:03:50.364467Z","steps":["trace[1560987516] 'agreement among raft nodes before linearized reading' (duration: 361.615248ms)","trace[1560987516] 'range keys from in-memory index tree' (duration: 79.250955ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:50.364535Z","caller":"traceutil/trace.go:171","msg":"trace[1861004892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2455; }","duration":"442.768779ms","start":"2026-05-29T22:03:49.921741Z","end":"2026-05-29T22:03:50.36451Z","steps":["trace[1861004892] 'agreement among raft nodes before linearized reading' (duration: 363.267889ms)","trace[1861004892] 'range keys from in-memory index tree' (duration: 79.278125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:50.364585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:49.921718Z","time spent":"442.858091ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T22:03:50.364607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:49.923367Z","time spent":"441.164708ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-29T22:03:50.364755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:49.937084Z","time spent":"427.65426ms","remote":"127.0.0.1:52210","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\" "}