{"level":"warn","ts":"2026-07-16T06:45:45.672814Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:45:45.672913Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119: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-07-16T06:45:45.673002Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:45:45.673017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-16T06:45:45.673036Z","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-07-16T06:45:45.673848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-07-16T06:45:45.673947Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119: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-07-16T06:45:45.678874Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.668511ms"} {"level":"info","ts":"2026-07-16T06:45:45.684994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-07-16T06:45:45.685067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:45:45.685093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-07-16T06:45:45.685107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:45:45.685118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-07-16T06:45:45.685169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-07-16T06:45:45.773472Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:45:45.776859Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:45:45.779352Z","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-07-16T06:45:45.783785Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:45:45.785358Z","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-07-16T06:45:45.785444Z","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-07-16T06:45:45.785456Z","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-07-16T06:45:45.785311Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:45:45.787442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-07-16T06:45:45.787538Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-16T06:45:45.788365Z","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-07-16T06:45:45.788486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-16T06:45:45.788567Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-16T06:45:45.788692Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:45:45.788757Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:45:46.388584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:45:46.388631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:45:46.388644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-07-16T06:45:46.388656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:45:46.388662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-16T06:45:46.38867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-07-16T06:45:46.388679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-16T06:45:46.389549Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:45:46.389664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:45:46.3897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:45:46.389914Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:45:46.390006Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:45:46.39003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:45:46.391574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-07-16T06:45:46.391634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:45:46.391673Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:45:46.391688Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:45:46.393538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:45:49.209436Z","caller":"traceutil/trace.go:171","msg":"trace[546885678] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"122.834732ms","start":"2026-07-16T06:45:49.086568Z","end":"2026-07-16T06:45:49.209402Z","steps":["trace[546885678] 'process raft request' (duration: 37.521462ms)","trace[546885678] 'compare' (duration: 85.173616ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:45:49.765649Z","caller":"traceutil/trace.go:171","msg":"trace[1947604798] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"287.86201ms","start":"2026-07-16T06:45:49.47776Z","end":"2026-07-16T06:45:49.765622Z","steps":["trace[1947604798] 'process raft request' (duration: 287.816089ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:45:49.765694Z","caller":"traceutil/trace.go:171","msg":"trace[1267681352] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"328.951759ms","start":"2026-07-16T06:45:49.436695Z","end":"2026-07-16T06:45:49.765647Z","steps":["trace[1267681352] 'process raft request' (duration: 240.235285ms)","trace[1267681352] 'compare' (duration: 88.523639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:45:49.766251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:45:49.436677Z","time spent":"329.104723ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:45:49.876407Z","caller":"traceutil/trace.go:171","msg":"trace[129842041] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"107.507364ms","start":"2026-07-16T06:45:49.76888Z","end":"2026-07-16T06:45:49.876387Z","steps":["trace[129842041] 'process raft request' (duration: 103.120135ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:45:49.876846Z","caller":"traceutil/trace.go:171","msg":"trace[398941440] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"105.849949ms","start":"2026-07-16T06:45:49.770969Z","end":"2026-07-16T06:45:49.876819Z","steps":["trace[398941440] 'process raft request' (duration: 105.748246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.153742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012424326s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T06:46:07.153857Z","caller":"traceutil/trace.go:171","msg":"trace[2079589627] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"1.337648856s","start":"2026-07-16T06:46:05.816191Z","end":"2026-07-16T06:46:07.15384Z","steps":["trace[2079589627] 'read index received' (duration: 324.951234ms)","trace[2079589627] 'applied index is now lower than readState.Index' (duration: 1.012696162s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:07.15398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337776089s","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-07-16T06:46:07.154014Z","caller":"traceutil/trace.go:171","msg":"trace[1218819935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"1.337855011s","start":"2026-07-16T06:46:05.81615Z","end":"2026-07-16T06:46:07.154005Z","steps":["trace[1218819935] 'agreement among raft nodes before linearized reading' (duration: 1.337769889s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.154045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:05.81613Z","time spent":"1.337906691s","remote":"127.0.0.1:59854","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-07-16T06:46:07.154161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145308815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:07.154249Z","caller":"traceutil/trace.go:171","msg":"trace[200651508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"1.145431737s","start":"2026-07-16T06:46:06.008795Z","end":"2026-07-16T06:46:07.154227Z","steps":["trace[200651508] 'agreement among raft nodes before linearized reading' (duration: 1.145291764s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.154234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.16964ms","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-07-16T06:46:07.154301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:06.008779Z","time spent":"1.145513619s","remote":"127.0.0.1:59610","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-07-16T06:46:07.154309Z","caller":"traceutil/trace.go:171","msg":"trace[1479928240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"379.243501ms","start":"2026-07-16T06:46:06.775052Z","end":"2026-07-16T06:46:07.154296Z","steps":["trace[1479928240] 'agreement among raft nodes before linearized reading' (duration: 379.130819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.15442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:06.774995Z","time spent":"379.391915ms","remote":"127.0.0.1:59854","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-07-16T06:46:07.155586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.435684ms","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-07-16T06:46:07.155681Z","caller":"traceutil/trace.go:171","msg":"trace[1842760792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"966.12607ms","start":"2026-07-16T06:46:06.189531Z","end":"2026-07-16T06:46:07.155657Z","steps":["trace[1842760792] 'agreement among raft nodes before linearized reading' (duration: 964.83203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.155771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:06.189511Z","time spent":"966.238542ms","remote":"127.0.0.1:59854","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-07-16T06:46:07.665251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450361,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:46:07.807308Z","caller":"traceutil/trace.go:171","msg":"trace[1243699433] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"646.46307ms","start":"2026-07-16T06:46:07.160825Z","end":"2026-07-16T06:46:07.807288Z","steps":["trace[1243699433] 'process raft request' (duration: 646.328737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.807574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:07.160789Z","time spent":"646.574713ms","remote":"127.0.0.1:59854","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-07-16T06:46:07.809998Z","caller":"traceutil/trace.go:171","msg":"trace[99860449] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"648.380026ms","start":"2026-07-16T06:46:07.161601Z","end":"2026-07-16T06:46:07.809981Z","steps":["trace[99860449] 'process raft request' (duration: 648.268454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.810134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:07.161592Z","time spent":"648.460299ms","remote":"127.0.0.1:59854","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-07-16T06:46:07.810118Z","caller":"traceutil/trace.go:171","msg":"trace[158899857] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"648.467058ms","start":"2026-07-16T06:46:07.161641Z","end":"2026-07-16T06:46:07.810108Z","steps":["trace[158899857] 'process raft request' (duration: 648.311044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.810315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:07.161636Z","time spent":"648.616982ms","remote":"127.0.0.1:59854","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-07-16T06:46:07.810525Z","caller":"traceutil/trace.go:171","msg":"trace[474960769] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:437; }","duration":"646.140294ms","start":"2026-07-16T06:46:07.164375Z","end":"2026-07-16T06:46:07.810516Z","steps":["trace[474960769] 'read index received' (duration: 642.832585ms)","trace[474960769] 'applied index is now lower than readState.Index' (duration: 3.307079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:07.810632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.251986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:07.810652Z","caller":"traceutil/trace.go:171","msg":"trace[1744981286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"646.274267ms","start":"2026-07-16T06:46:07.164372Z","end":"2026-07-16T06:46:07.810646Z","steps":["trace[1744981286] 'agreement among raft nodes before linearized reading' (duration: 646.197805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.810672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:07.164339Z","time spent":"646.328837ms","remote":"127.0.0.1:59610","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-07-16T06:46:07.810797Z","caller":"traceutil/trace.go:171","msg":"trace[2142866584] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"648.762924ms","start":"2026-07-16T06:46:07.162025Z","end":"2026-07-16T06:46:07.810788Z","steps":["trace[2142866584] 'process raft request' (duration: 648.046857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:07.810926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:07.162017Z","time spent":"648.882998ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:46:12.091388Z","caller":"traceutil/trace.go:171","msg":"trace[303138686] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"265.206972ms","start":"2026-07-16T06:46:11.826145Z","end":"2026-07-16T06:46:12.091352Z","steps":["trace[303138686] 'read index received' (duration: 265.027998ms)","trace[303138686] 'applied index is now lower than readState.Index' (duration: 177.624µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:12.091783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.345626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:46:12.09182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.660722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:46:12.091841Z","caller":"traceutil/trace.go:171","msg":"trace[1008508855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"262.423148ms","start":"2026-07-16T06:46:11.8294Z","end":"2026-07-16T06:46:12.091824Z","steps":["trace[1008508855] 'agreement among raft nodes before linearized reading' (duration: 262.328776ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:12.091869Z","caller":"traceutil/trace.go:171","msg":"trace[806736127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"265.726524ms","start":"2026-07-16T06:46:11.826129Z","end":"2026-07-16T06:46:12.091855Z","steps":["trace[806736127] 'agreement among raft nodes before linearized reading' (duration: 265.635762ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:12.091992Z","caller":"traceutil/trace.go:171","msg":"trace[1936225435] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"267.439723ms","start":"2026-07-16T06:46:11.824537Z","end":"2026-07-16T06:46:12.091977Z","steps":["trace[1936225435] 'process raft request' (duration: 266.622805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:12.092162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.653813ms","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-07-16T06:46:12.092228Z","caller":"traceutil/trace.go:171","msg":"trace[1117534891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"262.736426ms","start":"2026-07-16T06:46:11.829477Z","end":"2026-07-16T06:46:12.092213Z","steps":["trace[1117534891] 'agreement among raft nodes before linearized reading' (duration: 262.638162ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:14.443602Z","caller":"traceutil/trace.go:171","msg":"trace[1112382439] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"294.631727ms","start":"2026-07-16T06:46:14.148953Z","end":"2026-07-16T06:46:14.443585Z","steps":["trace[1112382439] 'process raft request' (duration: 294.522095ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:14.494431Z","caller":"traceutil/trace.go:171","msg":"trace[1572117648] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"343.236844ms","start":"2026-07-16T06:46:14.151155Z","end":"2026-07-16T06:46:14.494392Z","steps":["trace[1572117648] 'process raft request' (duration: 343.145622ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:14.494459Z","caller":"traceutil/trace.go:171","msg":"trace[793454029] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"345.113216ms","start":"2026-07-16T06:46:14.149318Z","end":"2026-07-16T06:46:14.494432Z","steps":["trace[793454029] 'process raft request' (duration: 344.84591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:14.494567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:14.15114Z","time spent":"343.346758ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:46:14.494602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:14.14929Z","time spent":"345.223539ms","remote":"127.0.0.1:59854","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-07-16T06:46:15.781852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.835427ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-16T06:46:17.578941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077037565s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T06:46:17.69844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.127146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:46:17.698562Z","caller":"traceutil/trace.go:171","msg":"trace[990784036] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"1.196739983s","start":"2026-07-16T06:46:16.501802Z","end":"2026-07-16T06:46:17.698542Z","steps":["trace[990784036] 'process raft request' (duration: 1.077434023s)","trace[990784036] 'compare' (duration: 119.003373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:17.698622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:16.501786Z","time spent":"1.196807114s","remote":"127.0.0.1:59854","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-07-16T06:46:17.844594Z","caller":"traceutil/trace.go:171","msg":"trace[1484784209] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:460; }","duration":"292.501771ms","start":"2026-07-16T06:46:17.55198Z","end":"2026-07-16T06:46:17.844482Z","steps":["trace[1484784209] 'read index received' (duration: 27.358916ms)","trace[1484784209] 'applied index is now lower than readState.Index' (duration: 265.141804ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:17.844684Z","caller":"traceutil/trace.go:171","msg":"trace[1593582858] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"1.342573928s","start":"2026-07-16T06:46:16.502067Z","end":"2026-07-16T06:46:17.844641Z","steps":["trace[1593582858] 'process raft request' (duration: 1.340451581s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:17.844732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.733395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-07-16T06:46:17.844779Z","caller":"traceutil/trace.go:171","msg":"trace[137607252] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:443; }","duration":"292.833278ms","start":"2026-07-16T06:46:17.551935Z","end":"2026-07-16T06:46:17.844768Z","steps":["trace[137607252] 'agreement among raft nodes before linearized reading' (duration: 292.724415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:17.844847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:16.502049Z","time spent":"1.342718692s","remote":"127.0.0.1:59854","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-07-16T06:46:18.155994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.931531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:18.156074Z","caller":"traceutil/trace.go:171","msg":"trace[348668530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"148.061914ms","start":"2026-07-16T06:46:18.007996Z","end":"2026-07-16T06:46:18.156058Z","steps":["trace[348668530] 'range keys from in-memory index tree' (duration: 147.776647ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:18.637433Z","caller":"traceutil/trace.go:171","msg":"trace[1614758334] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"153.073784ms","start":"2026-07-16T06:46:18.484339Z","end":"2026-07-16T06:46:18.637412Z","steps":["trace[1614758334] 'process raft request' (duration: 152.923601ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:18.646341Z","caller":"traceutil/trace.go:171","msg":"trace[102087171] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"160.496397ms","start":"2026-07-16T06:46:18.485824Z","end":"2026-07-16T06:46:18.646321Z","steps":["trace[102087171] 'process raft request' (duration: 160.239331ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:19.768584Z","caller":"traceutil/trace.go:171","msg":"trace[756080777] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"138.695059ms","start":"2026-07-16T06:46:19.629856Z","end":"2026-07-16T06:46:19.768551Z","steps":["trace[756080777] 'process raft request' (duration: 75.619541ms)","trace[756080777] 'compare' (duration: 62.946075ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:19.768778Z","caller":"traceutil/trace.go:171","msg":"trace[1985510832] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"125.03104ms","start":"2026-07-16T06:46:19.643729Z","end":"2026-07-16T06:46:19.76876Z","steps":["trace[1985510832] 'read index received' (duration: 61.734477ms)","trace[1985510832] 'applied index is now lower than readState.Index' (duration: 63.295953ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:19.768883Z","caller":"traceutil/trace.go:171","msg":"trace[1821949838] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"124.751434ms","start":"2026-07-16T06:46:19.644124Z","end":"2026-07-16T06:46:19.768875Z","steps":["trace[1821949838] 'process raft request' (duration: 124.55951ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:19.768956Z","caller":"traceutil/trace.go:171","msg":"trace[1066433106] transaction","detail":"{read_only:false; number_of_response:1; response_revision:460; }","duration":"127.970804ms","start":"2026-07-16T06:46:19.640947Z","end":"2026-07-16T06:46:19.768917Z","steps":["trace[1066433106] 'process raft request' (duration: 127.585216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:19.769047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.306676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-07-16T06:46:19.769073Z","caller":"traceutil/trace.go:171","msg":"trace[1672465980] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:461; }","duration":"125.381457ms","start":"2026-07-16T06:46:19.643684Z","end":"2026-07-16T06:46:19.769065Z","steps":["trace[1672465980] 'agreement among raft nodes before linearized reading' (duration: 125.333816ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:22.024086Z","caller":"traceutil/trace.go:171","msg":"trace[1640860181] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"159.112177ms","start":"2026-07-16T06:46:21.864936Z","end":"2026-07-16T06:46:22.024048Z","steps":["trace[1640860181] 'process raft request' (duration: 157.212366ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:24.481262Z","caller":"traceutil/trace.go:171","msg":"trace[1210674349] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"161.445846ms","start":"2026-07-16T06:46:24.319795Z","end":"2026-07-16T06:46:24.481241Z","steps":["trace[1210674349] 'process raft request' (duration: 161.343024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:25.86977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.215619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T06:46:26.94428Z","caller":"traceutil/trace.go:171","msg":"trace[1046865342] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"159.352862ms","start":"2026-07-16T06:46:26.7849Z","end":"2026-07-16T06:46:26.944253Z","steps":["trace[1046865342] 'process raft request' (duration: 159.25419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:33.315761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.061149ms","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-07-16T06:46:33.315856Z","caller":"traceutil/trace.go:171","msg":"trace[1718198224] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:543; }","duration":"232.216552ms","start":"2026-07-16T06:46:33.08362Z","end":"2026-07-16T06:46:33.315836Z","steps":["trace[1718198224] 'range keys from in-memory index tree' (duration: 231.991218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.479497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:34.738998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23140591s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:46:34.739313Z","caller":"traceutil/trace.go:171","msg":"trace[2022496730] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.231790328s","start":"2026-07-16T06:46:33.507496Z","end":"2026-07-16T06:46:34.739287Z","steps":["trace[2022496730] 'process raft request' (duration: 1.231665005s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.739459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.507477Z","time spent":"1.23191211s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:34.750573Z","caller":"traceutil/trace.go:171","msg":"trace[664316664] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.2294744s","start":"2026-07-16T06:46:33.521077Z","end":"2026-07-16T06:46:34.750551Z","steps":["trace[664316664] 'process raft request' (duration: 1.229269356s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.75064Z","caller":"traceutil/trace.go:171","msg":"trace[2043774100] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.224676813s","start":"2026-07-16T06:46:33.525942Z","end":"2026-07-16T06:46:34.750618Z","steps":["trace[2043774100] 'process raft request' (duration: 1.22454605s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.75076Z","caller":"traceutil/trace.go:171","msg":"trace[1510687728] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.229524811s","start":"2026-07-16T06:46:33.521205Z","end":"2026-07-16T06:46:34.75073Z","steps":["trace[1510687728] 'process raft request' (duration: 1.229245916s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.750763Z","caller":"traceutil/trace.go:171","msg":"trace[1117596348] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.221434288s","start":"2026-07-16T06:46:33.52927Z","end":"2026-07-16T06:46:34.750705Z","steps":["trace[1117596348] 'process raft request' (duration: 1.221310255s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.75077Z","caller":"traceutil/trace.go:171","msg":"trace[967362032] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.222557941s","start":"2026-07-16T06:46:33.528165Z","end":"2026-07-16T06:46:34.750723Z","steps":["trace[967362032] 'process raft request' (duration: 1.222365327s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.750845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.521162Z","time spent":"1.229626912s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:46:34.7509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.529262Z","time spent":"1.221578301s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:34.750931Z","caller":"traceutil/trace.go:171","msg":"trace[1924682768] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:567; }","duration":"772.174449ms","start":"2026-07-16T06:46:33.978744Z","end":"2026-07-16T06:46:34.750918Z","steps":["trace[1924682768] 'read index received' (duration: 760.72179ms)","trace[1924682768] 'applied index is now lower than readState.Index' (duration: 11.452079ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:34.750984Z","caller":"traceutil/trace.go:171","msg":"trace[1884499242] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.221060031s","start":"2026-07-16T06:46:33.529917Z","end":"2026-07-16T06:46:34.750977Z","steps":["trace[1884499242] 'process raft request' (duration: 1.220693734s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.751011Z","caller":"traceutil/trace.go:171","msg":"trace[819165379] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.220707584s","start":"2026-07-16T06:46:33.530297Z","end":"2026-07-16T06:46:34.751005Z","steps":["trace[819165379] 'process raft request' (duration: 1.220343466s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.751022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.529909Z","time spent":"1.221094752s","remote":"127.0.0.1:59654","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-07-16T06:46:34.751041Z","caller":"traceutil/trace.go:171","msg":"trace[1802816578] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.208331404s","start":"2026-07-16T06:46:33.542706Z","end":"2026-07-16T06:46:34.751037Z","steps":["trace[1802816578] 'process raft request' (duration: 1.207964547s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.751068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.542685Z","time spent":"1.208370706s","remote":"127.0.0.1:59654","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-07-16T06:46:34.751072Z","caller":"traceutil/trace.go:171","msg":"trace[632743562] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.208011579s","start":"2026-07-16T06:46:33.543052Z","end":"2026-07-16T06:46:34.751064Z","steps":["trace[632743562] 'process raft request' (duration: 1.207655032s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.752548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.543026Z","time spent":"1.209498649s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:46:34.752703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.945075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:46:34.751397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.528154Z","time spent":"1.222708094s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:34.752794Z","caller":"traceutil/trace.go:171","msg":"trace[128138787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"774.062217ms","start":"2026-07-16T06:46:33.978699Z","end":"2026-07-16T06:46:34.752761Z","steps":["trace[128138787] 'agreement among raft nodes before linearized reading' (duration: 773.845853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.752846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.978679Z","time spent":"774.156559ms","remote":"127.0.0.1:59854","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-07-16T06:46:34.751791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.530286Z","time spent":"1.220741844s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:34.751911Z","caller":"traceutil/trace.go:171","msg":"trace[939427093] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.183028357s","start":"2026-07-16T06:46:33.568785Z","end":"2026-07-16T06:46:34.751813Z","steps":["trace[939427093] 'process raft request' (duration: 1.182001396s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:34.751076Z","caller":"traceutil/trace.go:171","msg":"trace[512125678] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.063697612s","start":"2026-07-16T06:46:33.687372Z","end":"2026-07-16T06:46:34.751069Z","steps":["trace[512125678] 'process raft request' (duration: 1.063505418s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.75339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.687345Z","time spent":"1.066000069s","remote":"127.0.0.1:59654","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-07-16T06:46:34.752079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.521051Z","time spent":"1.229622683s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:34.751033Z","caller":"traceutil/trace.go:171","msg":"trace[1474830427] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.169181879s","start":"2026-07-16T06:46:33.581841Z","end":"2026-07-16T06:46:34.751023Z","steps":["trace[1474830427] 'process raft request' (duration: 1.168990825s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.754063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.581815Z","time spent":"1.172199169s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:46:34.754398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.568759Z","time spent":"1.184447466s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:46:34.755601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.337242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:34.75568Z","caller":"traceutil/trace.go:171","msg":"trace[1303314844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"737.451175ms","start":"2026-07-16T06:46:34.018209Z","end":"2026-07-16T06:46:34.755661Z","steps":["trace[1303314844] 'agreement among raft nodes before linearized reading' (duration: 737.321502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.75574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:34.018174Z","time spent":"737.557986ms","remote":"127.0.0.1:59610","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-07-16T06:46:34.756243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.633225ms","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-07-16T06:46:34.756316Z","caller":"traceutil/trace.go:171","msg":"trace[1497639390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"237.739117ms","start":"2026-07-16T06:46:34.518565Z","end":"2026-07-16T06:46:34.756304Z","steps":["trace[1497639390] 'agreement among raft nodes before linearized reading' (duration: 237.609995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.756528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.424946ms","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-07-16T06:46:34.75659Z","caller":"traceutil/trace.go:171","msg":"trace[679646806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"649.528318ms","start":"2026-07-16T06:46:34.107045Z","end":"2026-07-16T06:46:34.756573Z","steps":["trace[679646806] 'agreement among raft nodes before linearized reading' (duration: 649.409095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.756629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:34.107021Z","time spent":"649.600169ms","remote":"127.0.0.1:59854","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-07-16T06:46:34.754754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:33.525927Z","time spent":"1.224773665s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:40.860358Z","caller":"traceutil/trace.go:171","msg":"trace[1718775518] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"193.623663ms","start":"2026-07-16T06:46:40.66671Z","end":"2026-07-16T06:46:40.860334Z","steps":["trace[1718775518] 'process raft request' (duration: 193.385009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.102318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:40.666846Z","time spent":"435.467549ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-16T06:46:41.106058Z","caller":"traceutil/trace.go:171","msg":"trace[695171399] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:678; }","duration":"277.26626ms","start":"2026-07-16T06:46:40.828736Z","end":"2026-07-16T06:46:41.106002Z","steps":["trace[695171399] 'read index received' (duration: 31.407067ms)","trace[695171399] 'applied index is now lower than readState.Index' (duration: 245.857743ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:41.106832Z","caller":"traceutil/trace.go:171","msg":"trace[464191394] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"357.800998ms","start":"2026-07-16T06:46:40.749008Z","end":"2026-07-16T06:46:41.106809Z","steps":["trace[464191394] 'process raft request' (duration: 352.71785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.106981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:40.748988Z","time spent":"357.91438ms","remote":"127.0.0.1:59700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:46:41.106981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.230289ms","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-07-16T06:46:41.107399Z","caller":"traceutil/trace.go:171","msg":"trace[1649124703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"278.710718ms","start":"2026-07-16T06:46:40.828667Z","end":"2026-07-16T06:46:41.107378Z","steps":["trace[1649124703] 'agreement among raft nodes before linearized reading' (duration: 278.145637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.111732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.095013ms","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-07-16T06:46:41.1118Z","caller":"traceutil/trace.go:171","msg":"trace[111032479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"102.178676ms","start":"2026-07-16T06:46:41.009605Z","end":"2026-07-16T06:46:41.111784Z","steps":["trace[111032479] 'agreement among raft nodes before linearized reading' (duration: 102.018233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.112019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.789387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:46:41.111762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.298257ms","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-07-16T06:46:41.112177Z","caller":"traceutil/trace.go:171","msg":"trace[787335985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"282.748065ms","start":"2026-07-16T06:46:40.829419Z","end":"2026-07-16T06:46:41.112167Z","steps":["trace[787335985] 'agreement among raft nodes before linearized reading' (duration: 282.0084ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:41.112149Z","caller":"traceutil/trace.go:171","msg":"trace[1783106315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"102.93996ms","start":"2026-07-16T06:46:41.009182Z","end":"2026-07-16T06:46:41.112122Z","steps":["trace[1783106315] 'agreement among raft nodes before linearized reading' (duration: 102.268666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.112487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.886298ms","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-07-16T06:46:41.112529Z","caller":"traceutil/trace.go:171","msg":"trace[404148590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"282.99579ms","start":"2026-07-16T06:46:40.829525Z","end":"2026-07-16T06:46:41.112521Z","steps":["trace[404148590] 'agreement among raft nodes before linearized reading' (duration: 282.901388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.529961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.224578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:41.530072Z","caller":"traceutil/trace.go:171","msg":"trace[1977168385] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:657; }","duration":"310.459272ms","start":"2026-07-16T06:46:41.219562Z","end":"2026-07-16T06:46:41.530021Z","steps":["trace[1977168385] 'range keys from in-memory index tree' (duration: 310.107915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:41.530105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:41.219543Z","time spent":"310.555713ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-16T06:46:42.049467Z","caller":"traceutil/trace.go:171","msg":"trace[1111895828] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"100.486478ms","start":"2026-07-16T06:46:41.948856Z","end":"2026-07-16T06:46:42.049343Z","steps":["trace[1111895828] 'process raft request' (duration: 99.812296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.512274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:43.609804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.458902559s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:46:43.610116Z","caller":"traceutil/trace.go:171","msg":"trace[2138621915] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"598.894257ms","start":"2026-07-16T06:46:43.011204Z","end":"2026-07-16T06:46:43.610098Z","steps":["trace[2138621915] 'read index received' (duration: 598.747994ms)","trace[2138621915] 'applied index is now lower than readState.Index' (duration: 145.583µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:43.610161Z","caller":"traceutil/trace.go:171","msg":"trace[1341486606] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.459471892s","start":"2026-07-16T06:46:42.150667Z","end":"2026-07-16T06:46:43.610139Z","steps":["trace[1341486606] 'process raft request' (duration: 1.459328849s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.610245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:42.15062Z","time spent":"1.459579774s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:46:43.610335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.261026ms","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-07-16T06:46:43.610347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.169073ms","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-07-16T06:46:43.610397Z","caller":"traceutil/trace.go:171","msg":"trace[283996430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"474.313487ms","start":"2026-07-16T06:46:43.136059Z","end":"2026-07-16T06:46:43.610372Z","steps":["trace[283996430] 'agreement among raft nodes before linearized reading' (duration: 474.152054ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:43.610402Z","caller":"traceutil/trace.go:171","msg":"trace[1579446337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"424.222714ms","start":"2026-07-16T06:46:43.186164Z","end":"2026-07-16T06:46:43.610387Z","steps":["trace[1579446337] 'agreement among raft nodes before linearized reading' (duration: 424.134443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.610423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.206193ms","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-07-16T06:46:43.610446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.95021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:46:43.610458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.136002Z","time spent":"474.448331ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T06:46:43.610542Z","caller":"traceutil/trace.go:171","msg":"trace[541343387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"424.074452ms","start":"2026-07-16T06:46:43.186443Z","end":"2026-07-16T06:46:43.610517Z","steps":["trace[541343387] 'agreement among raft nodes before linearized reading' (duration: 423.883207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.61059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.186433Z","time spent":"424.148484ms","remote":"127.0.0.1:59854","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-07-16T06:46:43.610499Z","caller":"traceutil/trace.go:171","msg":"trace[891929468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"424.323067ms","start":"2026-07-16T06:46:43.186159Z","end":"2026-07-16T06:46:43.610482Z","steps":["trace[891929468] 'agreement among raft nodes before linearized reading' (duration: 424.112462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.610617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.159032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:46:43.61076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.186129Z","time spent":"424.620312ms","remote":"127.0.0.1:59854","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-07-16T06:46:43.610448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.186108Z","time spent":"424.332827ms","remote":"127.0.0.1:59854","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-07-16T06:46:43.610851Z","caller":"traceutil/trace.go:171","msg":"trace[765442955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"599.627022ms","start":"2026-07-16T06:46:43.011157Z","end":"2026-07-16T06:46:43.610784Z","steps":["trace[765442955] 'agreement among raft nodes before linearized reading' (duration: 599.018469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:43.61102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.01111Z","time spent":"599.893057ms","remote":"127.0.0.1:59610","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-07-16T06:46:44.117511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:44.618577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:45.119014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:45.620039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:45.843341Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.226748476s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T06:46:46.212929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.776213ms","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-07-16T06:46:46.213055Z","caller":"traceutil/trace.go:171","msg":"trace[196846112] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:659; }","duration":"228.940866ms","start":"2026-07-16T06:46:45.984097Z","end":"2026-07-16T06:46:46.213038Z","steps":["trace[196846112] 'range keys from in-memory index tree' (duration: 228.758691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:46.21406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.406532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:46:46.214218Z","caller":"traceutil/trace.go:171","msg":"trace[1921209907] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"2.597685177s","start":"2026-07-16T06:46:43.616475Z","end":"2026-07-16T06:46:46.21416Z","steps":["trace[1921209907] 'process raft request' (duration: 2.227054181s)","trace[1921209907] 'compare' (duration: 369.987225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:46.214433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.616455Z","time spent":"2.597876511s","remote":"127.0.0.1:59854","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-07-16T06:46:46.247751Z","caller":"traceutil/trace.go:171","msg":"trace[822095866] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"2.630732588s","start":"2026-07-16T06:46:43.616992Z","end":"2026-07-16T06:46:46.247724Z","steps":["trace[822095866] 'process raft request' (duration: 2.630650516s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:46.247925Z","caller":"traceutil/trace.go:171","msg":"trace[813208551] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"2.63087157s","start":"2026-07-16T06:46:43.617042Z","end":"2026-07-16T06:46:46.247914Z","steps":["trace[813208551] 'process raft request' (duration: 2.630644806s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:46.247985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.617034Z","time spent":"2.630919981s","remote":"127.0.0.1:59854","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-07-16T06:46:46.24797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.616978Z","time spent":"2.6308509s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:46:46.248241Z","caller":"traceutil/trace.go:171","msg":"trace[556738378] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"2.631326869s","start":"2026-07-16T06:46:43.616878Z","end":"2026-07-16T06:46:46.248205Z","steps":["trace[556738378] 'process raft request' (duration: 2.630608745s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:46.24831Z","caller":"traceutil/trace.go:171","msg":"trace[1742033513] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.242874935s","start":"2026-07-16T06:46:45.005407Z","end":"2026-07-16T06:46:46.248282Z","steps":["trace[1742033513] 'process raft request' (duration: 1.242467587s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:46.248412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.616854Z","time spent":"2.631466582s","remote":"127.0.0.1:59854","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-07-16T06:46:46.248438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:45.005382Z","time spent":"1.242991128s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:46:46.265106Z","caller":"traceutil/trace.go:171","msg":"trace[1828196952] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:689; }","duration":"2.647700226s","start":"2026-07-16T06:46:43.617383Z","end":"2026-07-16T06:46:46.265083Z","steps":["trace[1828196952] 'read index received' (duration: 2.226159155s)","trace[1828196952] 'applied index is now lower than readState.Index' (duration: 421.537641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:46.26529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64788431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-16T06:46:46.265318Z","caller":"traceutil/trace.go:171","msg":"trace[1226737001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:664; }","duration":"2.647951731s","start":"2026-07-16T06:46:43.617359Z","end":"2026-07-16T06:46:46.26531Z","steps":["trace[1226737001] 'agreement among raft nodes before linearized reading' (duration: 2.647846909s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:46.265351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:43.617352Z","time spent":"2.647990521s","remote":"127.0.0.1:59792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-16T06:46:46.265448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256023801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:46.265577Z","caller":"traceutil/trace.go:171","msg":"trace[1859730374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"1.256330526s","start":"2026-07-16T06:46:45.009226Z","end":"2026-07-16T06:46:46.265557Z","steps":["trace[1859730374] 'agreement among raft nodes before linearized reading' (duration: 1.25601215s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:46.266423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:45.009215Z","time spent":"1.257189603s","remote":"127.0.0.1:59610","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-07-16T06:46:46.782848Z","caller":"traceutil/trace.go:171","msg":"trace[1188145702] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"104.085442ms","start":"2026-07-16T06:46:46.678741Z","end":"2026-07-16T06:46:46.782826Z","steps":["trace[1188145702] 'process raft request' (duration: 103.946899ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:46.792604Z","caller":"traceutil/trace.go:171","msg":"trace[1958507631] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"100.116638ms","start":"2026-07-16T06:46:46.69247Z","end":"2026-07-16T06:46:46.792587Z","steps":["trace[1958507631] 'process raft request' (duration: 100.043866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.507697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450853,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:46:48.572577Z","caller":"traceutil/trace.go:171","msg":"trace[1349890967] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"645.139391ms","start":"2026-07-16T06:46:47.927344Z","end":"2026-07-16T06:46:48.572484Z","steps":["trace[1349890967] 'process raft request' (duration: 645.000769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.572718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:47.927326Z","time spent":"645.323064ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:48.579163Z","caller":"traceutil/trace.go:171","msg":"trace[903687723] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"451.538468ms","start":"2026-07-16T06:46:48.127611Z","end":"2026-07-16T06:46:48.579149Z","steps":["trace[903687723] 'process raft request' (duration: 451.390985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.579254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.563035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:48.579243Z","caller":"traceutil/trace.go:171","msg":"trace[275359320] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"238.108214ms","start":"2026-07-16T06:46:48.341105Z","end":"2026-07-16T06:46:48.579213Z","steps":["trace[275359320] 'process raft request' (duration: 237.983222ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:48.57928Z","caller":"traceutil/trace.go:171","msg":"trace[523837789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"572.627817ms","start":"2026-07-16T06:46:48.006645Z","end":"2026-07-16T06:46:48.579273Z","steps":["trace[523837789] 'agreement among raft nodes before linearized reading' (duration: 572.552366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.579303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.006631Z","time spent":"572.667827ms","remote":"127.0.0.1:59596","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-07-16T06:46:48.579293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.127574Z","time spent":"451.66403ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:46:48.579166Z","caller":"traceutil/trace.go:171","msg":"trace[1732832673] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:719; }","duration":"572.464924ms","start":"2026-07-16T06:46:48.006682Z","end":"2026-07-16T06:46:48.579147Z","steps":["trace[1732832673] 'read index received' (duration: 565.694948ms)","trace[1732832673] 'applied index is now lower than readState.Index' (duration: 6.767746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:48.584193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.187708ms","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-07-16T06:46:48.584233Z","caller":"traceutil/trace.go:171","msg":"trace[828964399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"343.260209ms","start":"2026-07-16T06:46:48.240962Z","end":"2026-07-16T06:46:48.584222Z","steps":["trace[828964399] 'agreement among raft nodes before linearized reading' (duration: 343.136297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.584276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.240944Z","time spent":"343.324731ms","remote":"127.0.0.1:59854","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-07-16T06:46:48.584291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.323546ms","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-07-16T06:46:48.584342Z","caller":"traceutil/trace.go:171","msg":"trace[649035698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"332.393537ms","start":"2026-07-16T06:46:48.251935Z","end":"2026-07-16T06:46:48.584328Z","steps":["trace[649035698] 'agreement among raft nodes before linearized reading' (duration: 332.223484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.584378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.251927Z","time spent":"332.444888ms","remote":"127.0.0.1:59854","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-07-16T06:46:48.584419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.762684ms","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-07-16T06:46:48.584438Z","caller":"traceutil/trace.go:171","msg":"trace[1600718694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"332.811985ms","start":"2026-07-16T06:46:48.251621Z","end":"2026-07-16T06:46:48.584433Z","steps":["trace[1600718694] 'agreement among raft nodes before linearized reading' (duration: 332.762354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.584452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.251603Z","time spent":"332.846515ms","remote":"127.0.0.1:59854","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-07-16T06:46:48.58461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.720314ms","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-07-16T06:46:48.584666Z","caller":"traceutil/trace.go:171","msg":"trace[517294906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"331.815686ms","start":"2026-07-16T06:46:48.252838Z","end":"2026-07-16T06:46:48.584653Z","steps":["trace[517294906] 'agreement among raft nodes before linearized reading' (duration: 331.642353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:48.5847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:48.252813Z","time spent":"331.877147ms","remote":"127.0.0.1:59854","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-07-16T06:46:49.852585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.024227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:46:49.852646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.452532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fqz98\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-07-16T06:46:49.852712Z","caller":"traceutil/trace.go:171","msg":"trace[641418395] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fqz98; range_end:; response_count:1; response_revision:725; }","duration":"150.530704ms","start":"2026-07-16T06:46:49.702167Z","end":"2026-07-16T06:46:49.852698Z","steps":["trace[641418395] 'range keys from in-memory index tree' (duration: 150.32045ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:49.852723Z","caller":"traceutil/trace.go:171","msg":"trace[54187736] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"127.03259ms","start":"2026-07-16T06:46:49.725662Z","end":"2026-07-16T06:46:49.852695Z","steps":["trace[54187736] 'read index received' (duration: 126.697983ms)","trace[54187736] 'applied index is now lower than readState.Index' (duration: 333.686µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:49.852873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.152002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:49.852907Z","caller":"traceutil/trace.go:171","msg":"trace[809453943] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"127.250683ms","start":"2026-07-16T06:46:49.725645Z","end":"2026-07-16T06:46:49.852896Z","steps":["trace[809453943] 'agreement among raft nodes before linearized reading' (duration: 127.144981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:49.853118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.294341ms","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-07-16T06:46:49.853201Z","caller":"traceutil/trace.go:171","msg":"trace[1993123381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"116.425893ms","start":"2026-07-16T06:46:49.736751Z","end":"2026-07-16T06:46:49.853177Z","steps":["trace[1993123381] 'agreement among raft nodes before linearized reading' (duration: 116.095647ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:49.852695Z","caller":"traceutil/trace.go:171","msg":"trace[1581164713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"327.1821ms","start":"2026-07-16T06:46:49.525486Z","end":"2026-07-16T06:46:49.852668Z","steps":["trace[1581164713] 'range keys from in-memory index tree' (duration: 326.911836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:49.853583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:49.525457Z","time spent":"328.101247ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-16T06:46:50.425743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450909,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:50.92668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534450909,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:46:50.934026Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065776607s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:46:51.00725Z","caller":"traceutil/trace.go:171","msg":"trace[993152551] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"1.082325505s","start":"2026-07-16T06:46:49.924906Z","end":"2026-07-16T06:46:51.007232Z","steps":["trace[993152551] 'read index received' (duration: 1.00917017s)","trace[993152551] 'applied index is now lower than readState.Index' (duration: 73.154465ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:51.007318Z","caller":"traceutil/trace.go:171","msg":"trace[1678851779] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.139177858s","start":"2026-07-16T06:46:49.86812Z","end":"2026-07-16T06:46:51.007298Z","steps":["trace[1678851779] 'process raft request' (duration: 1.066017822s)","trace[1678851779] 'compare' (duration: 72.956412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:46:51.007409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:49.868094Z","time spent":"1.139262029s","remote":"127.0.0.1:59778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:46:51.007445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082525719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-16T06:46:51.007475Z","caller":"traceutil/trace.go:171","msg":"trace[1657631505] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"1.082607911s","start":"2026-07-16T06:46:49.924858Z","end":"2026-07-16T06:46:51.007466Z","steps":["trace[1657631505] 'agreement among raft nodes before linearized reading' (duration: 1.082469698s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:49.924838Z","time spent":"1.082658441s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-07-16T06:46:51.007558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.103231ms","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-07-16T06:46:51.00761Z","caller":"traceutil/trace.go:171","msg":"trace[1162437278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"408.167643ms","start":"2026-07-16T06:46:50.599429Z","end":"2026-07-16T06:46:51.007597Z","steps":["trace[1162437278] 'agreement among raft nodes before linearized reading' (duration: 407.99606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.599422Z","time spent":"408.213794ms","remote":"127.0.0.1:59854","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-07-16T06:46:51.007692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.318184ms","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-07-16T06:46:51.007713Z","caller":"traceutil/trace.go:171","msg":"trace[2140158229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"409.370624ms","start":"2026-07-16T06:46:50.598337Z","end":"2026-07-16T06:46:51.007707Z","steps":["trace[2140158229] 'agreement among raft nodes before linearized reading' (duration: 409.327553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.59832Z","time spent":"409.406375ms","remote":"127.0.0.1:59854","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-07-16T06:46:51.00765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.546842ms","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-07-16T06:46:51.00777Z","caller":"traceutil/trace.go:171","msg":"trace[997451847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"413.699504ms","start":"2026-07-16T06:46:50.594066Z","end":"2026-07-16T06:46:51.007766Z","steps":["trace[997451847] 'agreement among raft nodes before linearized reading' (duration: 413.523051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.107211ms","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-07-16T06:46:51.007786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.594049Z","time spent":"413.733925ms","remote":"127.0.0.1:59854","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-07-16T06:46:51.007797Z","caller":"traceutil/trace.go:171","msg":"trace[1514240206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"408.141942ms","start":"2026-07-16T06:46:50.599648Z","end":"2026-07-16T06:46:51.00779Z","steps":["trace[1514240206] 'agreement among raft nodes before linearized reading' (duration: 408.073341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.599643Z","time spent":"408.168233ms","remote":"127.0.0.1:59854","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-07-16T06:46:51.00783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.906065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T06:46:51.007842Z","caller":"traceutil/trace.go:171","msg":"trace[2055501347] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:728; }","duration":"345.951486ms","start":"2026-07-16T06:46:50.661887Z","end":"2026-07-16T06:46:51.007839Z","steps":["trace[2055501347] 'agreement among raft nodes before linearized reading' (duration: 345.926216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.007854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.661867Z","time spent":"345.984556ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"warn","ts":"2026-07-16T06:46:51.007701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.815599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-16T06:46:51.007978Z","caller":"traceutil/trace.go:171","msg":"trace[1207752343] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"882.156095ms","start":"2026-07-16T06:46:50.1258Z","end":"2026-07-16T06:46:51.007956Z","steps":["trace[1207752343] 'agreement among raft nodes before linearized reading' (duration: 881.609816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.008008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.700234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-16T06:46:51.008048Z","caller":"traceutil/trace.go:171","msg":"trace[301149356] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"482.781865ms","start":"2026-07-16T06:46:50.525255Z","end":"2026-07-16T06:46:51.008037Z","steps":["trace[301149356] 'agreement among raft nodes before linearized reading' (duration: 482.709164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.008079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.525227Z","time spent":"482.841316ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-16T06:46:51.008136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.878688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-16T06:46:51.008166Z","caller":"traceutil/trace.go:171","msg":"trace[1631507160] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"682.940889ms","start":"2026-07-16T06:46:50.325217Z","end":"2026-07-16T06:46:51.008158Z","steps":["trace[1631507160] 'agreement among raft nodes before linearized reading' (duration: 682.876398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.008129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.125778Z","time spent":"882.310068ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-07-16T06:46:51.008206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.016548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-16T06:46:51.008229Z","caller":"traceutil/trace.go:171","msg":"trace[1317139480] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"283.085959ms","start":"2026-07-16T06:46:50.725137Z","end":"2026-07-16T06:46:51.008223Z","steps":["trace[1317139480] 'agreement among raft nodes before linearized reading' (duration: 282.423757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:51.008185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:50.325198Z","time spent":"682.982619ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-16T06:46:52.05039Z","caller":"traceutil/trace.go:171","msg":"trace[570699906] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"179.439323ms","start":"2026-07-16T06:46:51.870925Z","end":"2026-07-16T06:46:52.050364Z","steps":["trace[570699906] 'process raft request' (duration: 174.406242ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:52.053054Z","caller":"traceutil/trace.go:171","msg":"trace[1868218395] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"102.492393ms","start":"2026-07-16T06:46:51.950544Z","end":"2026-07-16T06:46:52.053036Z","steps":["trace[1868218395] 'process raft request' (duration: 101.801451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:02.529804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534451200,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:47:03.030963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534451200,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:47:03.531548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534451200,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:47:03.747882Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.967329098s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:47:03.748357Z","caller":"traceutil/trace.go:171","msg":"trace[119394526] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"1.737989487s","start":"2026-07-16T06:47:02.010351Z","end":"2026-07-16T06:47:03.74834Z","steps":["trace[119394526] 'read index received' (duration: 1.737666371s)","trace[119394526] 'applied index is now lower than readState.Index' (duration: 322.126µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:47:03.748521Z","caller":"traceutil/trace.go:171","msg":"trace[610944577] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.967955579s","start":"2026-07-16T06:47:01.780445Z","end":"2026-07-16T06:47:03.748401Z","steps":["trace[610944577] 'process raft request' (duration: 1.967761095s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.749197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:01.780426Z","time spent":"1.968721082s","remote":"127.0.0.1:59778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:47:03.748599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.342972ms","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-07-16T06:47:03.749331Z","caller":"traceutil/trace.go:171","msg":"trace[806526284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"658.078084ms","start":"2026-07-16T06:47:03.091225Z","end":"2026-07-16T06:47:03.749303Z","steps":["trace[806526284] 'agreement among raft nodes before linearized reading' (duration: 657.307321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.749408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:03.091214Z","time spent":"658.176667ms","remote":"127.0.0.1:59854","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-07-16T06:47:03.74866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.819923ms","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-07-16T06:47:03.749498Z","caller":"traceutil/trace.go:171","msg":"trace[516520757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"657.660248ms","start":"2026-07-16T06:47:03.091826Z","end":"2026-07-16T06:47:03.749486Z","steps":["trace[516520757] 'agreement among raft nodes before linearized reading' (duration: 656.804043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.749618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:03.09182Z","time spent":"657.79014ms","remote":"127.0.0.1:59854","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-07-16T06:47:03.748683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738133759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:47:03.749693Z","caller":"traceutil/trace.go:171","msg":"trace[1104758565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"1.739378992s","start":"2026-07-16T06:47:02.010309Z","end":"2026-07-16T06:47:03.749688Z","steps":["trace[1104758565] 'agreement among raft nodes before linearized reading' (duration: 1.738110069s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.749713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:02.01029Z","time spent":"1.739414652s","remote":"127.0.0.1:59610","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-07-16T06:47:03.748734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.487124ms","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-07-16T06:47:03.749864Z","caller":"traceutil/trace.go:171","msg":"trace[604783340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"658.653625ms","start":"2026-07-16T06:47:03.091181Z","end":"2026-07-16T06:47:03.749835Z","steps":["trace[604783340] 'agreement among raft nodes before linearized reading' (duration: 657.368952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.748758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632338771s","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-07-16T06:47:03.748901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.51871ms","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-07-16T06:47:03.74994Z","caller":"traceutil/trace.go:171","msg":"trace[233311130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"656.60809ms","start":"2026-07-16T06:47:03.093319Z","end":"2026-07-16T06:47:03.749927Z","steps":["trace[233311130] 'agreement among raft nodes before linearized reading' (duration: 655.291296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.749958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:03.091158Z","time spent":"658.793587ms","remote":"127.0.0.1:59854","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-07-16T06:47:03.750008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:03.093313Z","time spent":"656.664672ms","remote":"127.0.0.1:59854","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-07-16T06:47:03.750012Z","caller":"traceutil/trace.go:171","msg":"trace[9537154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"1.633620644s","start":"2026-07-16T06:47:02.116369Z","end":"2026-07-16T06:47:03.74999Z","steps":["trace[9537154] 'agreement among raft nodes before linearized reading' (duration: 1.632162728s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.750112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:02.116355Z","time spent":"1.633738006s","remote":"127.0.0.1:59854","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-07-16T06:47:03.751098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336876021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:47:03.751406Z","caller":"traceutil/trace.go:171","msg":"trace[1006434193] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:823; }","duration":"1.337852167s","start":"2026-07-16T06:47:02.413386Z","end":"2026-07-16T06:47:03.751238Z","steps":["trace[1006434193] 'agreement among raft nodes before linearized reading' (duration: 1.335239332s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:03.752886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:02.413368Z","time spent":"1.339498277s","remote":"127.0.0.1:59742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:47:22.013525Z","caller":"traceutil/trace.go:171","msg":"trace[1657292948] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"167.052291ms","start":"2026-07-16T06:47:21.846404Z","end":"2026-07-16T06:47:22.013456Z","steps":["trace[1657292948] 'process raft request' (duration: 166.876698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:22.20953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.453238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:47:22.209769Z","caller":"traceutil/trace.go:171","msg":"trace[1881861407] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"278.328464ms","start":"2026-07-16T06:47:21.931422Z","end":"2026-07-16T06:47:22.20975Z","steps":["trace[1881861407] 'process raft request' (duration: 278.229683ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:22.209749Z","caller":"traceutil/trace.go:171","msg":"trace[1462742292] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"360.820539ms","start":"2026-07-16T06:47:21.848908Z","end":"2026-07-16T06:47:22.209729Z","steps":["trace[1462742292] 'read index received' (duration: 164.404879ms)","trace[1462742292] 'applied index is now lower than readState.Index' (duration: 196.41426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:47:22.209919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.006092ms","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-07-16T06:47:22.209935Z","caller":"traceutil/trace.go:171","msg":"trace[2037024186] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"363.071565ms","start":"2026-07-16T06:47:21.846847Z","end":"2026-07-16T06:47:22.209918Z","steps":["trace[2037024186] 'process raft request' (duration: 261.14335ms)","trace[2037024186] 'compare' (duration: 101.322736ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:47:22.209948Z","caller":"traceutil/trace.go:171","msg":"trace[445830608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"361.052402ms","start":"2026-07-16T06:47:21.848888Z","end":"2026-07-16T06:47:22.20994Z","steps":["trace[445830608] 'agreement among raft nodes before linearized reading' (duration: 360.951241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:22.209977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:21.848881Z","time spent":"361.087303ms","remote":"127.0.0.1:59854","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-07-16T06:47:22.210044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.717554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:47:22.210014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:21.846841Z","time spent":"363.125636ms","remote":"127.0.0.1:59854","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-07-16T06:47:22.210092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.959922ms","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-07-16T06:47:22.210062Z","caller":"traceutil/trace.go:171","msg":"trace[156637936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"199.763054ms","start":"2026-07-16T06:47:22.010292Z","end":"2026-07-16T06:47:22.210055Z","steps":["trace[156637936] 'agreement among raft nodes before linearized reading' (duration: 199.737474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:22.210146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.260371ms","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-07-16T06:47:22.210167Z","caller":"traceutil/trace.go:171","msg":"trace[1760363693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"357.290531ms","start":"2026-07-16T06:47:21.852871Z","end":"2026-07-16T06:47:22.210161Z","steps":["trace[1760363693] 'agreement among raft nodes before linearized reading' (duration: 357.23604ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:22.210158Z","caller":"traceutil/trace.go:171","msg":"trace[1670863847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"358.025603ms","start":"2026-07-16T06:47:21.852117Z","end":"2026-07-16T06:47:22.210143Z","steps":["trace[1670863847] 'agreement among raft nodes before linearized reading' (duration: 357.88121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:22.210183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:21.852866Z","time spent":"357.313001ms","remote":"127.0.0.1:59854","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-07-16T06:47:22.210212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:21.852101Z","time spent":"358.086813ms","remote":"127.0.0.1:59854","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-07-16T06:47:50.524555Z","caller":"traceutil/trace.go:171","msg":"trace[2135800178] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"121.813726ms","start":"2026-07-16T06:47:50.402711Z","end":"2026-07-16T06:47:50.524525Z","steps":["trace[2135800178] 'process raft request' (duration: 120.591757ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:50.52452Z","caller":"traceutil/trace.go:171","msg":"trace[1543608508] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"116.059689ms","start":"2026-07-16T06:47:50.408408Z","end":"2026-07-16T06:47:50.524468Z","steps":["trace[1543608508] 'read index received' (duration: 114.83685ms)","trace[1543608508] 'applied index is now lower than readState.Index' (duration: 1.222019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:47:50.524764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.320763ms","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-07-16T06:47:50.52484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.694598ms","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-07-16T06:47:50.524878Z","caller":"traceutil/trace.go:171","msg":"trace[1868011594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"114.791559ms","start":"2026-07-16T06:47:50.41008Z","end":"2026-07-16T06:47:50.524872Z","steps":["trace[1868011594] 'agreement among raft nodes before linearized reading' (duration: 114.657387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:50.524905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.031098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:47:50.524928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.008168ms","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-07-16T06:47:50.524964Z","caller":"traceutil/trace.go:171","msg":"trace[1939486657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"110.106599ms","start":"2026-07-16T06:47:50.414842Z","end":"2026-07-16T06:47:50.524949Z","steps":["trace[1939486657] 'agreement among raft nodes before linearized reading' (duration: 109.971197ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:50.524992Z","caller":"traceutil/trace.go:171","msg":"trace[778365127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"110.101869ms","start":"2026-07-16T06:47:50.414875Z","end":"2026-07-16T06:47:50.524977Z","steps":["trace[778365127] 'agreement among raft nodes before linearized reading' (duration: 109.884796ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:50.525499Z","caller":"traceutil/trace.go:171","msg":"trace[68083237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"117.119024ms","start":"2026-07-16T06:47:50.408362Z","end":"2026-07-16T06:47:50.525481Z","steps":["trace[68083237] 'agreement among raft nodes before linearized reading' (duration: 116.240521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:55.99517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.183801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T06:47:56.790608Z","caller":"traceutil/trace.go:171","msg":"trace[1027067621] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"117.459118ms","start":"2026-07-16T06:47:56.673134Z","end":"2026-07-16T06:47:56.790593Z","steps":["trace[1027067621] 'process raft request' (duration: 117.152583ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:00.577988Z","caller":"traceutil/trace.go:171","msg":"trace[517506423] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"107.991384ms","start":"2026-07-16T06:48:00.469968Z","end":"2026-07-16T06:48:00.577959Z","steps":["trace[517506423] 'process raft request' (duration: 107.790751ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:07.047278Z","caller":"traceutil/trace.go:171","msg":"trace[2141997885] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"156.481243ms","start":"2026-07-16T06:48:06.890773Z","end":"2026-07-16T06:48:07.047255Z","steps":["trace[2141997885] 'process raft request' (duration: 144.123354ms)","trace[2141997885] 'compare' (duration: 12.112516ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:07.047431Z","caller":"traceutil/trace.go:171","msg":"trace[845944057] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"156.438413ms","start":"2026-07-16T06:48:06.890972Z","end":"2026-07-16T06:48:07.04741Z","steps":["trace[845944057] 'process raft request' (duration: 156.208969ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:07.047702Z","caller":"traceutil/trace.go:171","msg":"trace[1274076065] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"156.656845ms","start":"2026-07-16T06:48:06.891031Z","end":"2026-07-16T06:48:07.047688Z","steps":["trace[1274076065] 'process raft request' (duration: 156.332941ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:13.180906Z","caller":"traceutil/trace.go:171","msg":"trace[1513931072] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"108.887389ms","start":"2026-07-16T06:48:13.071987Z","end":"2026-07-16T06:48:13.180875Z","steps":["trace[1513931072] 'process raft request' (duration: 108.495944ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:19.083238Z","caller":"traceutil/trace.go:171","msg":"trace[1115228496] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"142.744121ms","start":"2026-07-16T06:48:18.940476Z","end":"2026-07-16T06:48:19.08322Z","steps":["trace[1115228496] 'read index received' (duration: 142.538548ms)","trace[1115228496] 'applied index is now lower than readState.Index' (duration: 204.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:19.083287Z","caller":"traceutil/trace.go:171","msg":"trace[1565936628] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"152.215916ms","start":"2026-07-16T06:48:18.931041Z","end":"2026-07-16T06:48:19.083257Z","steps":["trace[1565936628] 'process raft request' (duration: 152.057644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:19.08342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.933434ms","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-07-16T06:48:19.083444Z","caller":"traceutil/trace.go:171","msg":"trace[471972214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"142.998956ms","start":"2026-07-16T06:48:18.940439Z","end":"2026-07-16T06:48:19.083438Z","steps":["trace[471972214] 'agreement among raft nodes before linearized reading' (duration: 142.893063ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:23.170875Z","caller":"traceutil/trace.go:171","msg":"trace[2020124608] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"161.453294ms","start":"2026-07-16T06:48:23.009404Z","end":"2026-07-16T06:48:23.170857Z","steps":["trace[2020124608] 'read index received' (duration: 161.380343ms)","trace[2020124608] 'applied index is now lower than readState.Index' (duration: 72.061µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:23.171096Z","caller":"traceutil/trace.go:171","msg":"trace[1011333071] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"316.743173ms","start":"2026-07-16T06:48:22.854303Z","end":"2026-07-16T06:48:23.171047Z","steps":["trace[1011333071] 'process raft request' (duration: 316.455579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:23.171155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.729079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:48:23.172061Z","caller":"traceutil/trace.go:171","msg":"trace[439051974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"162.681141ms","start":"2026-07-16T06:48:23.009365Z","end":"2026-07-16T06:48:23.172046Z","steps":["trace[439051974] 'agreement among raft nodes before linearized reading' (duration: 161.711608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:23.17245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:22.854287Z","time spent":"317.061098ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:48:23.629328Z","caller":"traceutil/trace.go:171","msg":"trace[1890779602] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"458.034697ms","start":"2026-07-16T06:48:23.171272Z","end":"2026-07-16T06:48:23.629306Z","steps":["trace[1890779602] 'read index received' (duration: 444.889681ms)","trace[1890779602] 'applied index is now lower than readState.Index' (duration: 13.143856ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:23.629582Z","caller":"traceutil/trace.go:171","msg":"trace[628797946] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"503.518784ms","start":"2026-07-16T06:48:23.126048Z","end":"2026-07-16T06:48:23.629567Z","steps":["trace[628797946] 'process raft request' (duration: 490.165484ms)","trace[628797946] 'compare' (duration: 12.507558ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:23.629686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.126022Z","time spent":"503.601034ms","remote":"127.0.0.1:59854","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-07-16T06:48:23.629823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.005327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:48:23.629827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.724779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:48:23.629874Z","caller":"traceutil/trace.go:171","msg":"trace[1514934790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"320.070588ms","start":"2026-07-16T06:48:23.309796Z","end":"2026-07-16T06:48:23.629866Z","steps":["trace[1514934790] 'agreement among raft nodes before linearized reading' (duration: 319.958916ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:23.629907Z","caller":"traceutil/trace.go:171","msg":"trace[2100400503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"456.854411ms","start":"2026-07-16T06:48:23.173035Z","end":"2026-07-16T06:48:23.629889Z","steps":["trace[2100400503] 'agreement among raft nodes before linearized reading' (duration: 456.717249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:23.629921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.246979ms","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-07-16T06:48:23.629925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.309789Z","time spent":"320.129308ms","remote":"127.0.0.1:59854","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-07-16T06:48:23.629992Z","caller":"traceutil/trace.go:171","msg":"trace[995477922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"529.376142ms","start":"2026-07-16T06:48:23.100599Z","end":"2026-07-16T06:48:23.629976Z","steps":["trace[995477922] 'agreement among raft nodes before linearized reading' (duration: 529.160728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:23.630034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.10058Z","time spent":"529.447293ms","remote":"127.0.0.1:59854","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-07-16T06:48:23.629973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.17301Z","time spent":"456.954233ms","remote":"127.0.0.1:59596","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-07-16T06:48:23.630132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.660161ms","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-07-16T06:48:23.630066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.487418ms","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-07-16T06:48:23.630179Z","caller":"traceutil/trace.go:171","msg":"trace[495452033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"321.63751ms","start":"2026-07-16T06:48:23.308534Z","end":"2026-07-16T06:48:23.630172Z","steps":["trace[495452033] 'agreement among raft nodes before linearized reading' (duration: 321.457917ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:23.63019Z","caller":"traceutil/trace.go:171","msg":"trace[1284239152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"383.735482ms","start":"2026-07-16T06:48:23.246426Z","end":"2026-07-16T06:48:23.630162Z","steps":["trace[1284239152] 'agreement among raft nodes before linearized reading' (duration: 383.570189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:23.630208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.308485Z","time spent":"321.714041ms","remote":"127.0.0.1:59854","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-07-16T06:48:23.630218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:23.246404Z","time spent":"383.808363ms","remote":"127.0.0.1:59854","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-07-16T06:48:33.822997Z","caller":"traceutil/trace.go:171","msg":"trace[1438653445] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"119.326503ms","start":"2026-07-16T06:48:33.703643Z","end":"2026-07-16T06:48:33.822969Z","steps":["trace[1438653445] 'read index received' (duration: 59.522775ms)","trace[1438653445] 'applied index is now lower than readState.Index' (duration: 59.802588ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:33.823143Z","caller":"traceutil/trace.go:171","msg":"trace[1014922882] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"126.403602ms","start":"2026-07-16T06:48:33.696694Z","end":"2026-07-16T06:48:33.823097Z","steps":["trace[1014922882] 'process raft request' (duration: 66.534343ms)","trace[1014922882] 'compare' (duration: 59.618306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:33.823389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.712528ms","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-07-16T06:48:33.82348Z","caller":"traceutil/trace.go:171","msg":"trace[1395944905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"119.841979ms","start":"2026-07-16T06:48:33.703618Z","end":"2026-07-16T06:48:33.82346Z","steps":["trace[1395944905] 'agreement among raft nodes before linearized reading' (duration: 119.561906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:33.823636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.006538ms","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-07-16T06:48:33.823727Z","caller":"traceutil/trace.go:171","msg":"trace[1612199903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"119.10933ms","start":"2026-07-16T06:48:33.70458Z","end":"2026-07-16T06:48:33.823689Z","steps":["trace[1612199903] 'agreement among raft nodes before linearized reading' (duration: 118.901257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:33.823644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.585413ms","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-07-16T06:48:33.823953Z","caller":"traceutil/trace.go:171","msg":"trace[1236180442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"118.791165ms","start":"2026-07-16T06:48:33.705033Z","end":"2026-07-16T06:48:33.823824Z","steps":["trace[1236180442] 'agreement among raft nodes before linearized reading' (duration: 118.486481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:50.307983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.321802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-16T06:48:50.308052Z","caller":"traceutil/trace.go:171","msg":"trace[754952756] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1330; }","duration":"100.446413ms","start":"2026-07-16T06:48:50.20759Z","end":"2026-07-16T06:48:50.308036Z","steps":["trace[754952756] 'range keys from in-memory index tree' (duration: 100.099369ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:50.798969Z","caller":"traceutil/trace.go:171","msg":"trace[1736991903] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"102.712735ms","start":"2026-07-16T06:48:50.696233Z","end":"2026-07-16T06:48:50.798946Z","steps":["trace[1736991903] 'process raft request' (duration: 52.25312ms)","trace[1736991903] 'compare' (duration: 50.245962ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:51.352211Z","caller":"traceutil/trace.go:171","msg":"trace[488476112] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"272.119205ms","start":"2026-07-16T06:48:51.08005Z","end":"2026-07-16T06:48:51.352169Z","steps":["trace[488476112] 'read index received' (duration: 193.685176ms)","trace[488476112] 'applied index is now lower than readState.Index' (duration: 78.432789ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:51.352252Z","caller":"traceutil/trace.go:171","msg":"trace[1801652001] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"396.133152ms","start":"2026-07-16T06:48:50.956099Z","end":"2026-07-16T06:48:51.352232Z","steps":["trace[1801652001] 'process raft request' (duration: 317.612682ms)","trace[1801652001] 'compare' (duration: 78.077943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:51.352355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:50.956052Z","time spent":"396.250683ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1857,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:51.352437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.374809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:48:51.352544Z","caller":"traceutil/trace.go:171","msg":"trace[1347432153] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1333; }","duration":"272.503431ms","start":"2026-07-16T06:48:51.079976Z","end":"2026-07-16T06:48:51.352479Z","steps":["trace[1347432153] 'agreement among raft nodes before linearized reading' (duration: 272.314208ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:56.023706Z","caller":"traceutil/trace.go:171","msg":"trace[477833230] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"199.677676ms","start":"2026-07-16T06:48:55.824007Z","end":"2026-07-16T06:48:56.023685Z","steps":["trace[477833230] 'process raft request' (duration: 182.180297ms)","trace[477833230] 'compare' (duration: 16.998473ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:57.612277Z","caller":"traceutil/trace.go:171","msg":"trace[182037333] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"104.921225ms","start":"2026-07-16T06:48:57.507335Z","end":"2026-07-16T06:48:57.612256Z","steps":["trace[182037333] 'process raft request' (duration: 104.786833ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.186719Z","caller":"traceutil/trace.go:171","msg":"trace[636327739] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"131.465217ms","start":"2026-07-16T06:48:58.055231Z","end":"2026-07-16T06:48:58.186696Z","steps":["trace[636327739] 'process raft request' (duration: 131.391336ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.186752Z","caller":"traceutil/trace.go:171","msg":"trace[1091748107] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"132.168377ms","start":"2026-07-16T06:48:58.054556Z","end":"2026-07-16T06:48:58.186725Z","steps":["trace[1091748107] 'process raft request' (duration: 98.79684ms)","trace[1091748107] 'compare' (duration: 33.155904ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:58.186796Z","caller":"traceutil/trace.go:171","msg":"trace[1556248636] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"115.998196ms","start":"2026-07-16T06:48:58.070762Z","end":"2026-07-16T06:48:58.18676Z","steps":["trace[1556248636] 'process raft request' (duration: 115.900175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:59.970975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.639965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-07-16T06:48:59.971084Z","caller":"traceutil/trace.go:171","msg":"trace[1318273513] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1366; }","duration":"171.743486ms","start":"2026-07-16T06:48:59.799287Z","end":"2026-07-16T06:48:59.97103Z","steps":["trace[1318273513] 'range keys from in-memory index tree' (duration: 171.514453ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:00.077797Z","caller":"traceutil/trace.go:171","msg":"trace[367096468] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"100.663275ms","start":"2026-07-16T06:48:59.97712Z","end":"2026-07-16T06:49:00.077783Z","steps":["trace[367096468] 'process raft request' (duration: 100.578734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:00.608895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.019806ms","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-07-16T06:49:00.608973Z","caller":"traceutil/trace.go:171","msg":"trace[1840554281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"418.117607ms","start":"2026-07-16T06:49:00.190836Z","end":"2026-07-16T06:49:00.608954Z","steps":["trace[1840554281] 'range keys from in-memory index tree' (duration: 417.873124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:00.608968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.452722ms","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-07-16T06:49:00.609017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:00.190831Z","time spent":"418.176878ms","remote":"127.0.0.1:59854","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-07-16T06:49:00.609033Z","caller":"traceutil/trace.go:171","msg":"trace[1863782963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1370; }","duration":"418.529893ms","start":"2026-07-16T06:49:00.190486Z","end":"2026-07-16T06:49:00.609015Z","steps":["trace[1863782963] 'range keys from in-memory index tree' (duration: 418.317469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:00.609068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.605963ms","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-07-16T06:49:00.609094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:00.190482Z","time spent":"418.599444ms","remote":"127.0.0.1:59854","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-07-16T06:49:00.609128Z","caller":"traceutil/trace.go:171","msg":"trace[1603536373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"418.695285ms","start":"2026-07-16T06:49:00.190419Z","end":"2026-07-16T06:49:00.609114Z","steps":["trace[1603536373] 'range keys from in-memory index tree' (duration: 418.433041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:00.60918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:00.190403Z","time spent":"418.764196ms","remote":"127.0.0.1:59854","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-07-16T06:49:01.603773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.221492ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-16T06:49:01.60399Z","caller":"traceutil/trace.go:171","msg":"trace[1920727576] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"777.953674ms","start":"2026-07-16T06:49:00.826016Z","end":"2026-07-16T06:49:01.603969Z","steps":["trace[1920727576] 'read index received' (duration: 58.171µs)","trace[1920727576] 'applied index is now lower than readState.Index' (duration: 777.893853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:01.60412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:00.669989Z","time spent":"934.127786ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-16T06:49:01.604392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.249254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:01.60447Z","caller":"traceutil/trace.go:171","msg":"trace[1234326865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"596.375976ms","start":"2026-07-16T06:49:01.008077Z","end":"2026-07-16T06:49:01.604453Z","steps":["trace[1234326865] 'agreement among raft nodes before linearized reading' (duration: 596.229454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.604619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.587933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T06:49:01.604533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.008055Z","time spent":"596.469697ms","remote":"127.0.0.1:59610","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-07-16T06:49:01.604683Z","caller":"traceutil/trace.go:171","msg":"trace[1369980401] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1373; }","duration":"778.711885ms","start":"2026-07-16T06:49:00.825957Z","end":"2026-07-16T06:49:01.604669Z","steps":["trace[1369980401] 'agreement among raft nodes before linearized reading' (duration: 778.594493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.604631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.137011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:01.604766Z","caller":"traceutil/trace.go:171","msg":"trace[476588999] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1373; }","duration":"409.302494ms","start":"2026-07-16T06:49:01.195446Z","end":"2026-07-16T06:49:01.604748Z","steps":["trace[476588999] 'agreement among raft nodes before linearized reading' (duration: 409.131102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.604837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.19543Z","time spent":"409.396015ms","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-16T06:49:01.604711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:00.825933Z","time spent":"778.771905ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:49:02.108343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.390226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:02.108994Z","caller":"traceutil/trace.go:171","msg":"trace[1843192270] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"499.391591ms","start":"2026-07-16T06:49:01.609523Z","end":"2026-07-16T06:49:02.108915Z","steps":["trace[1843192270] 'read index received' (duration: 211.618877ms)","trace[1843192270] 'applied index is now lower than readState.Index' (duration: 287.769954ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:02.109251Z","caller":"traceutil/trace.go:171","msg":"trace[1433765468] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"503.273123ms","start":"2026-07-16T06:49:01.605941Z","end":"2026-07-16T06:49:02.109214Z","steps":["trace[1433765468] 'process raft request' (duration: 215.216555ms)","trace[1433765468] 'compare' (duration: 286.262994ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:02.109479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.942069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:02.10954Z","caller":"traceutil/trace.go:171","msg":"trace[1473897564] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1374; }","duration":"500.00434ms","start":"2026-07-16T06:49:01.609501Z","end":"2026-07-16T06:49:02.109505Z","steps":["trace[1473897564] 'agreement among raft nodes before linearized reading' (duration: 499.563003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:02.109566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.60949Z","time spent":"500.06917ms","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-16T06:49:02.109668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.605917Z","time spent":"503.387636ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:49:02.60951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534452485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:03.110198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534452485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:03.610795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334484534452485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:03.863762Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.980357814s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T06:49:04.009306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000645566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-16T06:49:04.009367Z","caller":"traceutil/trace.go:171","msg":"trace[401974738] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000755218s","start":"2026-07-16T06:49:02.008597Z","end":"2026-07-16T06:49:04.009353Z","steps":["trace[401974738] 'agreement among raft nodes before linearized reading' (duration: 2.000679187s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.009398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.008577Z","time spent":"2.000814789s","remote":"127.0.0.1:59610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/16 06:49:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-16T06:49:04.03089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.865472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:04.030959Z","caller":"traceutil/trace.go:171","msg":"trace[204803305] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"2.147650241s","start":"2026-07-16T06:49:01.883295Z","end":"2026-07-16T06:49:04.030945Z","steps":["trace[204803305] 'process raft request' (duration: 1.980671727s)","trace[204803305] 'compare' (duration: 166.445407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:04.030997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.883278Z","time spent":"2.147700801s","remote":"127.0.0.1:59854","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-07-16T06:49:04.378854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.295163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:04.378948Z","caller":"traceutil/trace.go:171","msg":"trace[1217442768] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"2.26989757s","start":"2026-07-16T06:49:02.109034Z","end":"2026-07-16T06:49:04.378931Z","steps":["trace[1217442768] 'read index received' (duration: 1.75491218s)","trace[1217442768] 'applied index is now lower than readState.Index' (duration: 514.98395ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:04.378974Z","caller":"traceutil/trace.go:171","msg":"trace[562512142] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"2.235687992s","start":"2026-07-16T06:49:02.143254Z","end":"2026-07-16T06:49:04.378942Z","steps":["trace[562512142] 'process raft request' (duration: 2.011215137s)","trace[562512142] 'compare' (duration: 224.145461ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:04.379052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.507583839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T06:49:04.379113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.677428367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:49:04.379139Z","caller":"traceutil/trace.go:171","msg":"trace[2130257783] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1376; }","duration":"2.507710961s","start":"2026-07-16T06:49:01.871414Z","end":"2026-07-16T06:49:04.379125Z","steps":["trace[2130257783] 'agreement among raft nodes before linearized reading' (duration: 2.50758854s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.379185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.871395Z","time spent":"2.507779662s","remote":"127.0.0.1:59762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:49:04.379195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.264912941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-16T06:49:04.379221Z","caller":"traceutil/trace.go:171","msg":"trace[644338913] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1376; }","duration":"2.264954782s","start":"2026-07-16T06:49:02.11426Z","end":"2026-07-16T06:49:04.379214Z","steps":["trace[644338913] 'agreement among raft nodes before linearized reading' (duration: 2.264910851s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.379256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.114247Z","time spent":"2.265002242s","remote":"127.0.0.1:59762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-16T06:49:04.379144Z","caller":"traceutil/trace.go:171","msg":"trace[1836897704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"2.677572139s","start":"2026-07-16T06:49:01.701563Z","end":"2026-07-16T06:49:04.379135Z","steps":["trace[1836897704] 'agreement among raft nodes before linearized reading' (duration: 2.677452297s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.379484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:01.701543Z","time spent":"2.677925674s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T06:49:04.379579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72636657s","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-07-16T06:49:04.379633Z","caller":"traceutil/trace.go:171","msg":"trace[138733229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.726445141s","start":"2026-07-16T06:49:02.653176Z","end":"2026-07-16T06:49:04.379621Z","steps":["trace[138733229] 'agreement among raft nodes before linearized reading' (duration: 1.726280609s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.379659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.653164Z","time spent":"1.726488692s","remote":"127.0.0.1:59854","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-07-16T06:49:04.379663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736206694s","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-07-16T06:49:04.379713Z","caller":"traceutil/trace.go:171","msg":"trace[505864820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.736264906s","start":"2026-07-16T06:49:02.643434Z","end":"2026-07-16T06:49:04.379699Z","steps":["trace[505864820] 'agreement among raft nodes before linearized reading' (duration: 1.736131474s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.37976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.079476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:49:04.379756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.143219Z","time spent":"2.235825664s","remote":"127.0.0.1:59854","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-07-16T06:49:04.379769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.64343Z","time spent":"1.736327847s","remote":"127.0.0.1:59854","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-07-16T06:49:04.379831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.235275757s","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-07-16T06:49:04.379779Z","caller":"traceutil/trace.go:171","msg":"trace[418019241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"370.118107ms","start":"2026-07-16T06:49:04.009655Z","end":"2026-07-16T06:49:04.379773Z","steps":["trace[418019241] 'agreement among raft nodes before linearized reading' (duration: 370.088527ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:04.37985Z","caller":"traceutil/trace.go:171","msg":"trace[386229554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"2.235331518s","start":"2026-07-16T06:49:02.144513Z","end":"2026-07-16T06:49:04.379845Z","steps":["trace[386229554] 'agreement among raft nodes before linearized reading' (duration: 2.235279687s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.379864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:04.009643Z","time spent":"370.215548ms","remote":"127.0.0.1:59596","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-07-16T06:49:04.379867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.14447Z","time spent":"2.235393108s","remote":"127.0.0.1:59854","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-07-16T06:49:04.379633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.546131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:49:04.379932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736505459s","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-07-16T06:49:04.380016Z","caller":"traceutil/trace.go:171","msg":"trace[615554091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.736563149s","start":"2026-07-16T06:49:02.64339Z","end":"2026-07-16T06:49:04.379953Z","steps":["trace[615554091] 'agreement among raft nodes before linearized reading' (duration: 1.736462308s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:04.380047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:02.643375Z","time spent":"1.7366658s","remote":"127.0.0.1:59854","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-07-16T06:49:04.379933Z","caller":"traceutil/trace.go:171","msg":"trace[975340893] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1376; }","duration":"156.877905ms","start":"2026-07-16T06:49:04.223047Z","end":"2026-07-16T06:49:04.379925Z","steps":["trace[975340893] 'agreement among raft nodes before linearized reading' (duration: 156.49476ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:20.62039Z","caller":"traceutil/trace.go:171","msg":"trace[323349697] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"108.579894ms","start":"2026-07-16T06:49:20.511786Z","end":"2026-07-16T06:49:20.620366Z","steps":["trace[323349697] 'read index received' (duration: 67.792541ms)","trace[323349697] 'applied index is now lower than readState.Index' (duration: 40.786383ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:20.620468Z","caller":"traceutil/trace.go:171","msg":"trace[1506403798] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"111.163815ms","start":"2026-07-16T06:49:20.509275Z","end":"2026-07-16T06:49:20.620439Z","steps":["trace[1506403798] 'process raft request' (duration: 70.338302ms)","trace[1506403798] 'compare' (duration: 40.647108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:20.620572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.759269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:20.620602Z","caller":"traceutil/trace.go:171","msg":"trace[1960493486] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:1434; }","duration":"108.837482ms","start":"2026-07-16T06:49:20.511754Z","end":"2026-07-16T06:49:20.620592Z","steps":["trace[1960493486] 'agreement among raft nodes before linearized reading' (duration: 108.76124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:20.877267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5435ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-16T06:49:20.87734Z","caller":"traceutil/trace.go:171","msg":"trace[751413376] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"188.58711ms","start":"2026-07-16T06:49:20.688741Z","end":"2026-07-16T06:49:20.877328Z","steps":["trace[751413376] 'read index received' (duration: 82.958278ms)","trace[751413376] 'applied index is now lower than readState.Index' (duration: 105.627822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:20.877437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.681363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:20.877475Z","caller":"traceutil/trace.go:171","msg":"trace[1388556726] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:1438; }","duration":"188.757956ms","start":"2026-07-16T06:49:20.688705Z","end":"2026-07-16T06:49:20.877463Z","steps":["trace[1388556726] 'agreement among raft nodes before linearized reading' (duration: 188.687773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:20.877695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.426866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T06:49:20.877788Z","caller":"traceutil/trace.go:171","msg":"trace[1836248999] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1438; }","duration":"181.544499ms","start":"2026-07-16T06:49:20.696222Z","end":"2026-07-16T06:49:20.877766Z","steps":["trace[1836248999] 'agreement among raft nodes before linearized reading' (duration: 181.300321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.136627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.658771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T06:49:21.136726Z","caller":"traceutil/trace.go:171","msg":"trace[890277941] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1439; }","duration":"106.805386ms","start":"2026-07-16T06:49:21.029898Z","end":"2026-07-16T06:49:21.136703Z","steps":["trace[890277941] 'range keys from in-memory index tree' (duration: 106.577458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:21.136749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.402704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:21.136815Z","caller":"traceutil/trace.go:171","msg":"trace[1275273181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1439; }","duration":"129.56886ms","start":"2026-07-16T06:49:21.00723Z","end":"2026-07-16T06:49:21.136799Z","steps":["trace[1275273181] 'range keys from in-memory index tree' (duration: 129.311351ms)"],"step_count":1}