{"level":"warn","ts":"2026-04-06T20:59:05.103281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T20:59:05.103378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-04-06T20:59:05.103528Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T20:59:05.103553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-06T20:59:05.103581Z","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-04-06T20:59:05.104449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-04-06T20:59:05.10452Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-04-06T20:59:05.128427Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"23.702101ms"} {"level":"info","ts":"2026-04-06T20:59:05.23873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-04-06T20:59:05.238823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T20:59:05.238847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-04-06T20:59:05.23886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T20:59:05.238869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-04-06T20:59:05.238907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-04-06T20:59:05.31176Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T20:59:05.329009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T20:59:05.348148Z","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-04-06T20:59:05.356787Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T20:59:05.356961Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-04-06T20:59:05.357161Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"4ec288cbdba84d36 cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-04-06T20:59:05.357107Z","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-04-06T20:59:05.359795Z","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-04-06T20:59:05.359861Z","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-04-06T20:59:05.36472Z","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-04-06T20:59:05.364773Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-06T20:59:05.36484Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-06T20:59:05.3655Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T20:59:05.365459Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T20:59:05.36581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-04-06T20:59:05.365944Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-06T20:59:06.343843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T20:59:06.343936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T20:59:06.344015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-04-06T20:59:06.344038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-04-06T20:59:06.344051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-06T20:59:06.34407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-04-06T20:59:06.344094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-06T20:59:06.354264Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T20:59:06.354367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T20:59:06.354346Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:06.354316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T20:59:06.354911Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T20:59:06.354955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T20:59:06.357407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T20:59:06.358336Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-04-06T20:59:06.360819Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:06.361191Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:06.361258Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-06T20:59:06.51015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.437707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:06.510195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.635098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:06.510249Z","caller":"traceutil/trace.go:171","msg":"trace[1385053569] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"103.704189ms","start":"2026-04-06T20:59:06.406533Z","end":"2026-04-06T20:59:06.510237Z","steps":["trace[1385053569] 'range keys from in-memory index tree' (duration: 103.381717ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:06.510238Z","caller":"traceutil/trace.go:171","msg":"trace[1167912454] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"103.595269ms","start":"2026-04-06T20:59:06.406624Z","end":"2026-04-06T20:59:06.510219Z","steps":["trace[1167912454] 'count revisions from in-memory index tree' (duration: 103.325096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.165124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.710619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:08.165352Z","caller":"traceutil/trace.go:171","msg":"trace[1811750920] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"451.920873ms","start":"2026-04-06T20:59:07.713415Z","end":"2026-04-06T20:59:08.165335Z","steps":["trace[1811750920] 'read index received' (duration: 57.372743ms)","trace[1811750920] 'applied index is now lower than readState.Index' (duration: 394.54726ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:08.165579Z","caller":"traceutil/trace.go:171","msg":"trace[1048209361] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"428.586735ms","start":"2026-04-06T20:59:07.73698Z","end":"2026-04-06T20:59:08.165567Z","steps":["trace[1048209361] 'process raft request' (duration: 428.282693ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.165578Z","caller":"traceutil/trace.go:171","msg":"trace[1983763146] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"453.746285ms","start":"2026-04-06T20:59:07.711768Z","end":"2026-04-06T20:59:08.165514Z","steps":["trace[1983763146] 'process raft request' (duration: 113.915359ms)","trace[1983763146] 'compare' (duration: 338.534748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:08.165712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.736962Z","time spent":"428.654216ms","remote":"127.0.0.1:54148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:08.16579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.341846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.165865Z","caller":"traceutil/trace.go:171","msg":"trace[1019486727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:4; }","duration":"452.433587ms","start":"2026-04-06T20:59:07.713403Z","end":"2026-04-06T20:59:08.165837Z","steps":["trace[1019486727] 'agreement among raft nodes before linearized reading' (duration: 452.247565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.16588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.711763Z","time spent":"454.013407ms","remote":"127.0.0.1:53926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:08.165936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.713398Z","time spent":"452.524017ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-06T20:59:08.219745Z","caller":"traceutil/trace.go:171","msg":"trace[999849008] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"424.82039ms","start":"2026-04-06T20:59:07.794838Z","end":"2026-04-06T20:59:08.219658Z","steps":["trace[999849008] 'process raft request' (duration: 424.539918ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.219867Z","caller":"traceutil/trace.go:171","msg":"trace[624825768] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"424.90799ms","start":"2026-04-06T20:59:07.794949Z","end":"2026-04-06T20:59:08.219857Z","steps":["trace[624825768] 'process raft request' (duration: 424.600738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.220002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.79483Z","time spent":"425.056061ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:08.220107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.794939Z","time spent":"425.054331ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:08.219763Z","caller":"traceutil/trace.go:171","msg":"trace[467173436] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"424.79161ms","start":"2026-04-06T20:59:07.794928Z","end":"2026-04-06T20:59:08.21972Z","steps":["trace[467173436] 'process raft request' (duration: 424.571369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.220246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.794924Z","time spent":"425.297923ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:08.22046Z","caller":"traceutil/trace.go:171","msg":"trace[1089225333] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"425.210842ms","start":"2026-04-06T20:59:07.795236Z","end":"2026-04-06T20:59:08.220446Z","steps":["trace[1089225333] 'process raft request' (duration: 424.385057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.220568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.795229Z","time spent":"425.283663ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:08.220698Z","caller":"traceutil/trace.go:171","msg":"trace[1968645916] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"425.279063ms","start":"2026-04-06T20:59:07.795394Z","end":"2026-04-06T20:59:08.220673Z","steps":["trace[1968645916] 'process raft request' (duration: 424.994991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.220807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.795387Z","time spent":"425.376073ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:08.220859Z","caller":"traceutil/trace.go:171","msg":"trace[509990874] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"425.834056ms","start":"2026-04-06T20:59:07.795014Z","end":"2026-04-06T20:59:08.220848Z","steps":["trace[509990874] 'process raft request' (duration: 424.585478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.220953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.795007Z","time spent":"425.918826ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:08.220986Z","caller":"traceutil/trace.go:171","msg":"trace[389577900] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"414.758897ms","start":"2026-04-06T20:59:07.806216Z","end":"2026-04-06T20:59:08.220975Z","steps":["trace[389577900] 'process raft request' (duration: 414.230733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.221098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.806203Z","time spent":"414.826407ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:08.221166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.00884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.221199Z","caller":"traceutil/trace.go:171","msg":"trace[952481430] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"455.07295ms","start":"2026-04-06T20:59:07.766119Z","end":"2026-04-06T20:59:08.221192Z","steps":["trace[952481430] 'agreement among raft nodes before linearized reading' (duration: 454.960619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.221216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.766076Z","time spent":"455.13685ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T20:59:08.221319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.216597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.221337Z","caller":"traceutil/trace.go:171","msg":"trace[1083391296] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"375.263397ms","start":"2026-04-06T20:59:07.846069Z","end":"2026-04-06T20:59:08.221333Z","steps":["trace[1083391296] 'agreement among raft nodes before linearized reading' (duration: 375.235657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.22135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.846049Z","time spent":"375.298327ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T20:59:08.221379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.538464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:08.221418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.213934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:08.221429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.277261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.22157Z","caller":"traceutil/trace.go:171","msg":"trace[1211699653] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"420.346141ms","start":"2026-04-06T20:59:07.801121Z","end":"2026-04-06T20:59:08.221468Z","steps":["trace[1211699653] 'agreement among raft nodes before linearized reading' (duration: 420.272151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.221621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.801111Z","time spent":"420.498192ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-06T20:59:08.221444Z","caller":"traceutil/trace.go:171","msg":"trace[1133395344] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"427.251894ms","start":"2026-04-06T20:59:07.794182Z","end":"2026-04-06T20:59:08.221434Z","steps":["trace[1133395344] 'agreement among raft nodes before linearized reading' (duration: 427.224284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.221684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.794175Z","time spent":"427.490576ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-06T20:59:08.221438Z","caller":"traceutil/trace.go:171","msg":"trace[747442715] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:0; response_revision:11; }","duration":"422.614295ms","start":"2026-04-06T20:59:07.798805Z","end":"2026-04-06T20:59:08.22142Z","steps":["trace[747442715] 'agreement among raft nodes before linearized reading' (duration: 422.540845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.222127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:07.798798Z","time spent":"423.110839ms","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-04-06T20:59:08.398916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.943708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:08.399099Z","caller":"traceutil/trace.go:171","msg":"trace[49604756] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"173.377116ms","start":"2026-04-06T20:59:08.22569Z","end":"2026-04-06T20:59:08.399067Z","steps":["trace[49604756] 'process raft request' (duration: 44.200107ms)","trace[49604756] 'compare' (duration: 128.828217ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:08.39925Z","caller":"traceutil/trace.go:171","msg":"trace[1391187764] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"172.258609ms","start":"2026-04-06T20:59:08.22697Z","end":"2026-04-06T20:59:08.399229Z","steps":["trace[1391187764] 'process raft request' (duration: 172.047408ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.399343Z","caller":"traceutil/trace.go:171","msg":"trace[1309709987] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"172.201199ms","start":"2026-04-06T20:59:08.227123Z","end":"2026-04-06T20:59:08.399324Z","steps":["trace[1309709987] 'process raft request' (duration: 171.999958ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.39938Z","caller":"traceutil/trace.go:171","msg":"trace[286604785] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"171.851266ms","start":"2026-04-06T20:59:08.227446Z","end":"2026-04-06T20:59:08.399298Z","steps":["trace[286604785] 'process raft request' (duration: 171.714545ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.3995Z","caller":"traceutil/trace.go:171","msg":"trace[941551188] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"171.412524ms","start":"2026-04-06T20:59:08.228074Z","end":"2026-04-06T20:59:08.399487Z","steps":["trace[941551188] 'process raft request' (duration: 171.171762ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.399433Z","caller":"traceutil/trace.go:171","msg":"trace[74207825] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"171.312143ms","start":"2026-04-06T20:59:08.228069Z","end":"2026-04-06T20:59:08.399381Z","steps":["trace[74207825] 'process raft request' (duration: 171.146562ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.399681Z","caller":"traceutil/trace.go:171","msg":"trace[155196941] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"172.37678ms","start":"2026-04-06T20:59:08.227293Z","end":"2026-04-06T20:59:08.39967Z","steps":["trace[155196941] 'read index received' (duration: 42.523987ms)","trace[155196941] 'applied index is now lower than readState.Index' (duration: 129.851853ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:08.39971Z","caller":"traceutil/trace.go:171","msg":"trace[2058532062] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"170.314877ms","start":"2026-04-06T20:59:08.229365Z","end":"2026-04-06T20:59:08.39968Z","steps":["trace[2058532062] 'process raft request' (duration: 170.178246ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.399769Z","caller":"traceutil/trace.go:171","msg":"trace[347757758] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"171.569954ms","start":"2026-04-06T20:59:08.228187Z","end":"2026-04-06T20:59:08.399757Z","steps":["trace[347757758] 'process raft request' (duration: 171.087671ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.399777Z","caller":"traceutil/trace.go:171","msg":"trace[105728498] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"169.884784ms","start":"2026-04-06T20:59:08.229884Z","end":"2026-04-06T20:59:08.399769Z","steps":["trace[105728498] 'process raft request' (duration: 169.695963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.399882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.555751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.399947Z","caller":"traceutil/trace.go:171","msg":"trace[699007312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"172.645831ms","start":"2026-04-06T20:59:08.227283Z","end":"2026-04-06T20:59:08.399929Z","steps":["trace[699007312] 'agreement among raft nodes before linearized reading' (duration: 172.534491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.400025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.601421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-06T20:59:08.40007Z","caller":"traceutil/trace.go:171","msg":"trace[1342808549] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"113.692471ms","start":"2026-04-06T20:59:08.286367Z","end":"2026-04-06T20:59:08.40006Z","steps":["trace[1342808549] 'agreement among raft nodes before linearized reading' (duration: 113.621281ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:08.400215Z","caller":"traceutil/trace.go:171","msg":"trace[1869860163] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"166.680384ms","start":"2026-04-06T20:59:08.233513Z","end":"2026-04-06T20:59:08.400194Z","steps":["trace[1869860163] 'process raft request' (duration: 166.10468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.987072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.39701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:08.987191Z","caller":"traceutil/trace.go:171","msg":"trace[1995334322] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:31; }","duration":"488.630081ms","start":"2026-04-06T20:59:08.498543Z","end":"2026-04-06T20:59:08.987173Z","steps":["trace[1995334322] 'read index received' (duration: 2.900718ms)","trace[1995334322] 'applied index is now lower than readState.Index' (duration: 485.728023ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:08.987316Z","caller":"traceutil/trace.go:171","msg":"trace[2041163125] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"534.060474ms","start":"2026-04-06T20:59:08.45324Z","end":"2026-04-06T20:59:08.987301Z","steps":["trace[2041163125] 'process raft request' (duration: 48.288762ms)","trace[2041163125] 'compare' (duration: 485.232159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:08.987384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.453234Z","time spent":"534.122795ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:08.987489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.371176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.987577Z","caller":"traceutil/trace.go:171","msg":"trace[1433752278] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"582.483247ms","start":"2026-04-06T20:59:08.405073Z","end":"2026-04-06T20:59:08.987556Z","steps":["trace[1433752278] 'agreement among raft nodes before linearized reading' (duration: 582.267096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.987543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.663448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:08.987712Z","caller":"traceutil/trace.go:171","msg":"trace[1624117104] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:28; }","duration":"558.87063ms","start":"2026-04-06T20:59:08.428821Z","end":"2026-04-06T20:59:08.987692Z","steps":["trace[1624117104] 'agreement among raft nodes before linearized reading' (duration: 558.490287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.987641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.405064Z","time spent":"582.567907ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-04-06T20:59:08.987848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.428812Z","time spent":"558.97254ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-04-06T20:59:08.988524Z","caller":"traceutil/trace.go:171","msg":"trace[103868750] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"486.392735ms","start":"2026-04-06T20:59:08.502118Z","end":"2026-04-06T20:59:08.98851Z","steps":["trace[103868750] 'process raft request' (duration: 486.287795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:08.988749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.502098Z","time spent":"486.515946ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:09.031668Z","caller":"traceutil/trace.go:171","msg":"trace[1716785449] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"528.515349ms","start":"2026-04-06T20:59:08.503124Z","end":"2026-04-06T20:59:09.031639Z","steps":["trace[1716785449] 'process raft request' (duration: 528.343908ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.031749Z","caller":"traceutil/trace.go:171","msg":"trace[1334364540] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"527.495063ms","start":"2026-04-06T20:59:08.504224Z","end":"2026-04-06T20:59:09.031719Z","steps":["trace[1334364540] 'process raft request' (duration: 527.428402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.031901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.504211Z","time spent":"527.610843ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:09.03176Z","caller":"traceutil/trace.go:171","msg":"trace[3353968] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"528.287898ms","start":"2026-04-06T20:59:08.503448Z","end":"2026-04-06T20:59:09.031735Z","steps":["trace[3353968] 'process raft request' (duration: 528.148647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.032052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.303197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:09.031961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.503109Z","time spent":"528.63662ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:09.032137Z","caller":"traceutil/trace.go:171","msg":"trace[2032265451] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:34; }","duration":"338.452438ms","start":"2026-04-06T20:59:08.693672Z","end":"2026-04-06T20:59:09.032124Z","steps":["trace[2032265451] 'agreement among raft nodes before linearized reading' (duration: 338.203407ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.032083Z","caller":"traceutil/trace.go:171","msg":"trace[1998364198] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"528.246837ms","start":"2026-04-06T20:59:08.503773Z","end":"2026-04-06T20:59:09.03202Z","steps":["trace[1998364198] 'process raft request' (duration: 527.852175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.032214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.693629Z","time spent":"338.569339ms","remote":"127.0.0.1:54296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-06T20:59:09.032293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.611409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.032338Z","caller":"traceutil/trace.go:171","msg":"trace[570851912] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:34; }","duration":"338.68804ms","start":"2026-04-06T20:59:08.693639Z","end":"2026-04-06T20:59:09.032327Z","steps":["trace[570851912] 'agreement among raft nodes before linearized reading' (duration: 338.60919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.032379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.69362Z","time spent":"338.75133ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-04-06T20:59:09.032369Z","caller":"traceutil/trace.go:171","msg":"trace[1212155107] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"528.563529ms","start":"2026-04-06T20:59:08.503245Z","end":"2026-04-06T20:59:09.031808Z","steps":["trace[1212155107] 'process raft request' (duration: 528.312968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.032392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.540122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:09.032292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.503757Z","time spent":"528.465319ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:09.032485Z","caller":"traceutil/trace.go:171","msg":"trace[1067339736] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:34; }","duration":"380.693163ms","start":"2026-04-06T20:59:08.65174Z","end":"2026-04-06T20:59:09.032433Z","steps":["trace[1067339736] 'agreement among raft nodes before linearized reading' (duration: 379.951738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.032493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.503238Z","time spent":"529.193103ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:09.032534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.651709Z","time spent":"380.814053ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-06T20:59:09.032074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:08.503439Z","time spent":"528.60615ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:09.032718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.129646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.032841Z","caller":"traceutil/trace.go:171","msg":"trace[405693381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34; }","duration":"226.576438ms","start":"2026-04-06T20:59:08.806246Z","end":"2026-04-06T20:59:09.032822Z","steps":["trace[405693381] 'agreement among raft nodes before linearized reading' (duration: 225.679613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.220967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.050005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:09.222034Z","caller":"traceutil/trace.go:171","msg":"trace[623552273] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"229.797199ms","start":"2026-04-06T20:59:08.99194Z","end":"2026-04-06T20:59:09.221737Z","steps":["trace[623552273] 'process raft request' (duration: 229.141815ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.222668Z","caller":"traceutil/trace.go:171","msg":"trace[357643401] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:38; }","duration":"186.955812ms","start":"2026-04-06T20:59:09.035655Z","end":"2026-04-06T20:59:09.22261Z","steps":["trace[357643401] 'read index received' (duration: 54.105756ms)","trace[357643401] 'applied index is now lower than readState.Index' (duration: 132.847926ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:09.223126Z","caller":"traceutil/trace.go:171","msg":"trace[1615885697] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"187.275004ms","start":"2026-04-06T20:59:09.035762Z","end":"2026-04-06T20:59:09.223037Z","steps":["trace[1615885697] 'process raft request' (duration: 186.51681ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.222696Z","caller":"traceutil/trace.go:171","msg":"trace[1469717052] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"186.136837ms","start":"2026-04-06T20:59:09.036349Z","end":"2026-04-06T20:59:09.222486Z","steps":["trace[1469717052] 'process raft request' (duration: 186.053977ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.224333Z","caller":"traceutil/trace.go:171","msg":"trace[497259221] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"232.156673ms","start":"2026-04-06T20:59:08.992107Z","end":"2026-04-06T20:59:09.224263Z","steps":["trace[497259221] 'process raft request' (duration: 230.03706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.224581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.911373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.224683Z","caller":"traceutil/trace.go:171","msg":"trace[449692902] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:39; }","duration":"189.057946ms","start":"2026-04-06T20:59:09.035609Z","end":"2026-04-06T20:59:09.224667Z","steps":["trace[449692902] 'agreement among raft nodes before linearized reading' (duration: 188.495662ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:09.226242Z","caller":"traceutil/trace.go:171","msg":"trace[408081031] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"236.32902ms","start":"2026-04-06T20:59:08.989849Z","end":"2026-04-06T20:59:09.226178Z","steps":["trace[408081031] 'process raft request' (duration: 99.978832ms)","trace[408081031] 'compare' (duration: 130.907964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:09.234908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.606651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.234982Z","caller":"traceutil/trace.go:171","msg":"trace[1490385783] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:39; }","duration":"147.080913ms","start":"2026-04-06T20:59:09.087876Z","end":"2026-04-06T20:59:09.234957Z","steps":["trace[1490385783] 'agreement among raft nodes before linearized reading' (duration: 135.817403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.816949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.154049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:09.817184Z","caller":"traceutil/trace.go:171","msg":"trace[505390073] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:44; }","duration":"562.266275ms","start":"2026-04-06T20:59:09.254885Z","end":"2026-04-06T20:59:09.817152Z","steps":["trace[505390073] 'read index received' (duration: 336.823375ms)","trace[505390073] 'applied index is now lower than readState.Index' (duration: 225.44027ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:09.81729Z","caller":"traceutil/trace.go:171","msg":"trace[299972621] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"561.091048ms","start":"2026-04-06T20:59:09.256134Z","end":"2026-04-06T20:59:09.817225Z","steps":["trace[299972621] 'process raft request' (duration: 560.937267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.81747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.256128Z","time spent":"561.260559ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:09.817533Z","caller":"traceutil/trace.go:171","msg":"trace[1474953055] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"579.813634ms","start":"2026-04-06T20:59:09.23765Z","end":"2026-04-06T20:59:09.817464Z","steps":["trace[1474953055] 'process raft request' (duration: 354.052112ms)","trace[1474953055] 'compare' (duration: 224.942447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:09.817643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.829564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.817717Z","caller":"traceutil/trace.go:171","msg":"trace[200866381] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:43; }","duration":"579.937015ms","start":"2026-04-06T20:59:09.23776Z","end":"2026-04-06T20:59:09.817697Z","steps":["trace[200866381] 'agreement among raft nodes before linearized reading' (duration: 579.750754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.8177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.237641Z","time spent":"579.975355ms","remote":"127.0.0.1:54296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:09.817773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.237742Z","time spent":"580.018616ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-04-06T20:59:09.818414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.413369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.818478Z","caller":"traceutil/trace.go:171","msg":"trace[1590014988] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:43; }","duration":"527.523489ms","start":"2026-04-06T20:59:09.290938Z","end":"2026-04-06T20:59:09.818462Z","steps":["trace[1590014988] 'agreement among raft nodes before linearized reading' (duration: 527.435068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.818524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.290911Z","time spent":"527.60255ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-06T20:59:09.818727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.365051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:09.818776Z","caller":"traceutil/trace.go:171","msg":"trace[341436591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"513.413562ms","start":"2026-04-06T20:59:09.30535Z","end":"2026-04-06T20:59:09.818764Z","steps":["trace[341436591] 'agreement among raft nodes before linearized reading' (duration: 513.343131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.818815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.305313Z","time spent":"513.493512ms","remote":"127.0.0.1:53858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-06T20:59:09.819437Z","caller":"traceutil/trace.go:171","msg":"trace[1737820777] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"559.350188ms","start":"2026-04-06T20:59:09.257846Z","end":"2026-04-06T20:59:09.817196Z","steps":["trace[1737820777] 'process raft request' (duration: 559.287337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:09.820398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:09.257842Z","time spent":"562.463907ms","remote":"127.0.0.1:54384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:10.099487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.257634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:10.099783Z","caller":"traceutil/trace.go:171","msg":"trace[1939018593] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"206.163507ms","start":"2026-04-06T20:59:09.893592Z","end":"2026-04-06T20:59:10.099755Z","steps":["trace[1939018593] 'read index received' (duration: 51.456021ms)","trace[1939018593] 'applied index is now lower than readState.Index' (duration: 154.704916ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:10.099816Z","caller":"traceutil/trace.go:171","msg":"trace[1995333939] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"273.742877ms","start":"2026-04-06T20:59:09.825945Z","end":"2026-04-06T20:59:10.099687Z","steps":["trace[1995333939] 'process raft request' (duration: 119.116851ms)","trace[1995333939] 'compare' (duration: 154.109783ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:10.099812Z","caller":"traceutil/trace.go:171","msg":"trace[2140950845] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"201.471558ms","start":"2026-04-06T20:59:09.89832Z","end":"2026-04-06T20:59:10.099792Z","steps":["trace[2140950845] 'process raft request' (duration: 201.437407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.100015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.415245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:10.100312Z","caller":"traceutil/trace.go:171","msg":"trace[1067481682] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:47; }","duration":"273.736357ms","start":"2026-04-06T20:59:09.826562Z","end":"2026-04-06T20:59:10.100298Z","steps":["trace[1067481682] 'agreement among raft nodes before linearized reading' (duration: 273.335654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.100079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.271572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:10.100406Z","caller":"traceutil/trace.go:171","msg":"trace[71426536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:47; }","duration":"202.602294ms","start":"2026-04-06T20:59:09.897792Z","end":"2026-04-06T20:59:10.100394Z","steps":["trace[71426536] 'agreement among raft nodes before linearized reading' (duration: 202.266742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.10003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.322503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:10.100472Z","caller":"traceutil/trace.go:171","msg":"trace[732146147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:47; }","duration":"202.765135ms","start":"2026-04-06T20:59:09.89767Z","end":"2026-04-06T20:59:10.100435Z","steps":["trace[732146147] 'agreement among raft nodes before linearized reading' (duration: 202.311083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.100123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.27183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:10.100514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.099185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:338"} {"level":"info","ts":"2026-04-06T20:59:10.100593Z","caller":"traceutil/trace.go:171","msg":"trace[1410044166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:47; }","duration":"184.775764ms","start":"2026-04-06T20:59:09.915805Z","end":"2026-04-06T20:59:10.100581Z","steps":["trace[1410044166] 'agreement among raft nodes before linearized reading' (duration: 184.246281ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:10.100607Z","caller":"traceutil/trace.go:171","msg":"trace[596243800] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:47; }","duration":"225.236425ms","start":"2026-04-06T20:59:09.875354Z","end":"2026-04-06T20:59:10.100591Z","steps":["trace[596243800] 'agreement among raft nodes before linearized reading' (duration: 224.454361ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:10.099865Z","caller":"traceutil/trace.go:171","msg":"trace[1563966755] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"201.90734ms","start":"2026-04-06T20:59:09.897932Z","end":"2026-04-06T20:59:10.099839Z","steps":["trace[1563966755] 'process raft request' (duration: 201.757789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.441244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.359961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:10.441457Z","caller":"traceutil/trace.go:171","msg":"trace[235828439] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"303.337302ms","start":"2026-04-06T20:59:10.138065Z","end":"2026-04-06T20:59:10.441402Z","steps":["trace[235828439] 'process raft request' (duration: 132.731429ms)","trace[235828439] 'compare' (duration: 170.229361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:10.44158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.138049Z","time spent":"303.495133ms","remote":"127.0.0.1:54384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:10.441808Z","caller":"traceutil/trace.go:171","msg":"trace[274197427] linearizableReadLoop","detail":"{readStateIndex:60; appliedIndex:58; }","duration":"297.034343ms","start":"2026-04-06T20:59:10.144745Z","end":"2026-04-06T20:59:10.44178Z","steps":["trace[274197427] 'read index received' (duration: 126.067798ms)","trace[274197427] 'applied index is now lower than readState.Index' (duration: 170.964585ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:10.44185Z","caller":"traceutil/trace.go:171","msg":"trace[497572205] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"301.527661ms","start":"2026-04-06T20:59:10.140196Z","end":"2026-04-06T20:59:10.441724Z","steps":["trace[497572205] 'process raft request' (duration: 301.145999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.441955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.194395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:10.442085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.140186Z","time spent":"301.776802ms","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:10.442166Z","caller":"traceutil/trace.go:171","msg":"trace[523590354] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:55; }","duration":"302.422286ms","start":"2026-04-06T20:59:10.139662Z","end":"2026-04-06T20:59:10.442085Z","steps":["trace[523590354] 'agreement among raft nodes before linearized reading' (duration: 302.142064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.44227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.139655Z","time spent":"302.560617ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"warn","ts":"2026-04-06T20:59:10.442267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.067101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-06T20:59:10.4424Z","caller":"traceutil/trace.go:171","msg":"trace[1046663493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:56; }","duration":"303.268802ms","start":"2026-04-06T20:59:10.139112Z","end":"2026-04-06T20:59:10.442381Z","steps":["trace[1046663493] 'agreement among raft nodes before linearized reading' (duration: 302.868869ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:10.442413Z","caller":"traceutil/trace.go:171","msg":"trace[352176420] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"294.93531ms","start":"2026-04-06T20:59:10.147455Z","end":"2026-04-06T20:59:10.44239Z","steps":["trace[352176420] 'process raft request' (duration: 294.215795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:10.442515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.139102Z","time spent":"303.399882ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-06T20:59:10.443724Z","caller":"traceutil/trace.go:171","msg":"trace[1625469022] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"294.718069ms","start":"2026-04-06T20:59:10.148986Z","end":"2026-04-06T20:59:10.443704Z","steps":["trace[1625469022] 'process raft request' (duration: 293.34463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:11.540636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.934014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:11.540764Z","caller":"traceutil/trace.go:171","msg":"trace[852121589] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"584.382953ms","start":"2026-04-06T20:59:10.956363Z","end":"2026-04-06T20:59:11.540746Z","steps":["trace[852121589] 'read index received' (duration: 45.233898ms)","trace[852121589] 'applied index is now lower than readState.Index' (duration: 539.147975ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:11.540837Z","caller":"traceutil/trace.go:171","msg":"trace[381962373] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"652.556935ms","start":"2026-04-06T20:59:10.888236Z","end":"2026-04-06T20:59:11.540793Z","steps":["trace[381962373] 'process raft request' (duration: 113.39188ms)","trace[381962373] 'compare' (duration: 538.797173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:11.540907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.535864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a3e02835296738\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-04-06T20:59:11.540939Z","caller":"traceutil/trace.go:171","msg":"trace[702909249] range","detail":"{range_begin:/registry/events/default/instance.18a3e02835296738; range_end:; response_count:1; response_revision:99; }","duration":"584.598825ms","start":"2026-04-06T20:59:10.956329Z","end":"2026-04-06T20:59:11.540928Z","steps":["trace[702909249] 'agreement among raft nodes before linearized reading' (duration: 584.505104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:11.540973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.956315Z","time spent":"584.651675ms","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":671,"request content":"key:\"/registry/events/default/instance.18a3e02835296738\" "} {"level":"warn","ts":"2026-04-06T20:59:11.541004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:10.888222Z","time spent":"652.698196ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2012,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:11.541147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.800943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:11.541122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.661381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:11.541223Z","caller":"traceutil/trace.go:171","msg":"trace[1032160729] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:99; }","duration":"316.816941ms","start":"2026-04-06T20:59:11.224385Z","end":"2026-04-06T20:59:11.541201Z","steps":["trace[1032160729] 'agreement among raft nodes before linearized reading' (duration: 316.656431ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:11.541222Z","caller":"traceutil/trace.go:171","msg":"trace[1270964308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:99; }","duration":"535.964514ms","start":"2026-04-06T20:59:11.005233Z","end":"2026-04-06T20:59:11.541198Z","steps":["trace[1270964308] 'agreement among raft nodes before linearized reading' (duration: 535.735323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:11.541274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:11.005212Z","time spent":"536.051726ms","remote":"127.0.0.1:53854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T20:59:11.541274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:11.224356Z","time spent":"316.907672ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-04-06T20:59:11.944018Z","caller":"traceutil/trace.go:171","msg":"trace[1505760475] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"140.876173ms","start":"2026-04-06T20:59:11.803093Z","end":"2026-04-06T20:59:11.943969Z","steps":["trace[1505760475] 'process raft request' (duration: 140.801793ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:11.944248Z","caller":"traceutil/trace.go:171","msg":"trace[1718764279] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"142.250941ms","start":"2026-04-06T20:59:11.801951Z","end":"2026-04-06T20:59:11.944202Z","steps":["trace[1718764279] 'process raft request' (duration: 70.623882ms)","trace[1718764279] 'compare' (duration: 71.051055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:12.218483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.701598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:12.218638Z","caller":"traceutil/trace.go:171","msg":"trace[176235675] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"227.326864ms","start":"2026-04-06T20:59:11.991286Z","end":"2026-04-06T20:59:12.218612Z","steps":["trace[176235675] 'process raft request' (duration: 76.364215ms)","trace[176235675] 'compare' (duration: 150.409356ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:12.246894Z","caller":"traceutil/trace.go:171","msg":"trace[445975829] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:125; }","duration":"141.101108ms","start":"2026-04-06T20:59:12.105769Z","end":"2026-04-06T20:59:12.24687Z","steps":["trace[445975829] 'read index received' (duration: 140.934037ms)","trace[445975829] 'applied index is now lower than readState.Index' (duration: 166.071µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:12.24695Z","caller":"traceutil/trace.go:171","msg":"trace[437120581] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"254.531269ms","start":"2026-04-06T20:59:11.992377Z","end":"2026-04-06T20:59:12.246908Z","steps":["trace[437120581] 'process raft request' (duration: 254.384448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:12.247329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.556941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:12.247404Z","caller":"traceutil/trace.go:171","msg":"trace[1870742329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"141.641962ms","start":"2026-04-06T20:59:12.105745Z","end":"2026-04-06T20:59:12.247387Z","steps":["trace[1870742329] 'agreement among raft nodes before linearized reading' (duration: 141.246519ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:12.414878Z","caller":"traceutil/trace.go:171","msg":"trace[1064411709] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:127; }","duration":"107.674965ms","start":"2026-04-06T20:59:12.307178Z","end":"2026-04-06T20:59:12.414853Z","steps":["trace[1064411709] 'read index received' (duration: 62.759262ms)","trace[1064411709] 'applied index is now lower than readState.Index' (duration: 44.914593ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:12.41504Z","caller":"traceutil/trace.go:171","msg":"trace[1433264553] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"164.188789ms","start":"2026-04-06T20:59:12.250691Z","end":"2026-04-06T20:59:12.41488Z","steps":["trace[1433264553] 'process raft request' (duration: 119.215865ms)","trace[1433264553] 'compare' (duration: 44.668363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:12.415246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.048987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:12.415365Z","caller":"traceutil/trace.go:171","msg":"trace[341320529] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:0; response_revision:123; }","duration":"108.21933ms","start":"2026-04-06T20:59:12.307124Z","end":"2026-04-06T20:59:12.415344Z","steps":["trace[341320529] 'agreement among raft nodes before linearized reading' (duration: 107.806967ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:12.929535Z","caller":"traceutil/trace.go:171","msg":"trace[2043944805] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"151.219131ms","start":"2026-04-06T20:59:12.778287Z","end":"2026-04-06T20:59:12.929507Z","steps":["trace[2043944805] 'process raft request' (duration: 85.780442ms)","trace[2043944805] 'compare' (duration: 65.266138ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:12.929697Z","caller":"traceutil/trace.go:171","msg":"trace[489175465] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"143.936805ms","start":"2026-04-06T20:59:12.78553Z","end":"2026-04-06T20:59:12.929467Z","steps":["trace[489175465] 'read index received' (duration: 78.534607ms)","trace[489175465] 'applied index is now lower than readState.Index' (duration: 65.401018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:12.929787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.237458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:12.929859Z","caller":"traceutil/trace.go:171","msg":"trace[1233496406] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:0; response_revision:144; }","duration":"144.381139ms","start":"2026-04-06T20:59:12.785457Z","end":"2026-04-06T20:59:12.929839Z","steps":["trace[1233496406] 'agreement among raft nodes before linearized reading' (duration: 144.212208ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:13.781105Z","caller":"traceutil/trace.go:171","msg":"trace[1800168841] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:169; }","duration":"175.629652ms","start":"2026-04-06T20:59:13.605448Z","end":"2026-04-06T20:59:13.781077Z","steps":["trace[1800168841] 'read index received' (duration: 93.206894ms)","trace[1800168841] 'applied index is now lower than readState.Index' (duration: 82.421738ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:13.781185Z","caller":"traceutil/trace.go:171","msg":"trace[1887164535] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"190.509913ms","start":"2026-04-06T20:59:13.590648Z","end":"2026-04-06T20:59:13.781158Z","steps":["trace[1887164535] 'process raft request' (duration: 107.921873ms)","trace[1887164535] 'compare' (duration: 82.356768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:13.781337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.862513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:13.781596Z","caller":"traceutil/trace.go:171","msg":"trace[1198113617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"176.149396ms","start":"2026-04-06T20:59:13.605419Z","end":"2026-04-06T20:59:13.781568Z","steps":["trace[1198113617] 'agreement among raft nodes before linearized reading' (duration: 175.825174ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:14.051297Z","caller":"traceutil/trace.go:171","msg":"trace[1695094798] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"170.042577ms","start":"2026-04-06T20:59:13.88123Z","end":"2026-04-06T20:59:14.051272Z","steps":["trace[1695094798] 'process raft request' (duration: 94.733703ms)","trace[1695094798] 'compare' (duration: 75.176882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:14.256105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.574443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:14.256258Z","caller":"traceutil/trace.go:171","msg":"trace[518907787] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:0; response_revision:172; }","duration":"103.797134ms","start":"2026-04-06T20:59:14.152429Z","end":"2026-04-06T20:59:14.256226Z","steps":["trace[518907787] 'range keys from in-memory index tree' (duration: 103.487822ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:14.770717Z","caller":"traceutil/trace.go:171","msg":"trace[476368751] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"162.940879ms","start":"2026-04-06T20:59:14.607726Z","end":"2026-04-06T20:59:14.770667Z","steps":["trace[476368751] 'process raft request' (duration: 157.817778ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:14.770746Z","caller":"traceutil/trace.go:171","msg":"trace[1283752433] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"162.106094ms","start":"2026-04-06T20:59:14.608582Z","end":"2026-04-06T20:59:14.770688Z","steps":["trace[1283752433] 'process raft request' (duration: 161.848843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:15.592337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.717585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:15.59272Z","caller":"traceutil/trace.go:171","msg":"trace[1983410675] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"162.635711ms","start":"2026-04-06T20:59:15.430058Z","end":"2026-04-06T20:59:15.592694Z","steps":["trace[1983410675] 'process raft request' (duration: 47.482074ms)","trace[1983410675] 'compare' (duration: 114.577184ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:15.59306Z","caller":"traceutil/trace.go:171","msg":"trace[2129659750] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"117.507042ms","start":"2026-04-06T20:59:15.475541Z","end":"2026-04-06T20:59:15.593048Z","steps":["trace[2129659750] 'read index received' (duration: 2.003323ms)","trace[2129659750] 'applied index is now lower than readState.Index' (duration: 115.502939ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:15.593164Z","caller":"traceutil/trace.go:171","msg":"trace[1904160504] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"122.525162ms","start":"2026-04-06T20:59:15.470629Z","end":"2026-04-06T20:59:15.593154Z","steps":["trace[1904160504] 'process raft request' (duration: 122.008619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:15.593512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.946445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-06T20:59:15.593551Z","caller":"traceutil/trace.go:171","msg":"trace[847585923] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:208; }","duration":"118.050905ms","start":"2026-04-06T20:59:15.475489Z","end":"2026-04-06T20:59:15.59354Z","steps":["trace[847585923] 'agreement among raft nodes before linearized reading' (duration: 117.605972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:15.975522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.005108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:15.975609Z","caller":"traceutil/trace.go:171","msg":"trace[394531371] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:216; }","duration":"270.863028ms","start":"2026-04-06T20:59:15.704732Z","end":"2026-04-06T20:59:15.975595Z","steps":["trace[394531371] 'read index received' (duration: 123.475698ms)","trace[394531371] 'applied index is now lower than readState.Index' (duration: 147.38632ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:15.975675Z","caller":"traceutil/trace.go:171","msg":"trace[1073283784] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"285.592495ms","start":"2026-04-06T20:59:15.690073Z","end":"2026-04-06T20:59:15.975666Z","steps":["trace[1073283784] 'process raft request' (duration: 138.245505ms)","trace[1073283784] 'compare' (duration: 146.879287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:15.975887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.15849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-06T20:59:15.975933Z","caller":"traceutil/trace.go:171","msg":"trace[978488801] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:212; }","duration":"271.245921ms","start":"2026-04-06T20:59:15.704679Z","end":"2026-04-06T20:59:15.975925Z","steps":["trace[978488801] 'agreement among raft nodes before linearized reading' (duration: 271.11205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:15.976129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.305298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:15.976158Z","caller":"traceutil/trace.go:171","msg":"trace[969686171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:212; }","duration":"170.387358ms","start":"2026-04-06T20:59:15.805764Z","end":"2026-04-06T20:59:15.976151Z","steps":["trace[969686171] 'agreement among raft nodes before linearized reading' (duration: 170.341528ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:16.224629Z","caller":"traceutil/trace.go:171","msg":"trace[1162990883] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"166.83163ms","start":"2026-04-06T20:59:16.057762Z","end":"2026-04-06T20:59:16.224594Z","steps":["trace[1162990883] 'process raft request' (duration: 125.224873ms)","trace[1162990883] 'compare' (duration: 41.464976ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:16.743793Z","caller":"traceutil/trace.go:171","msg":"trace[201148467] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"141.884113ms","start":"2026-04-06T20:59:16.601876Z","end":"2026-04-06T20:59:16.74376Z","steps":["trace[201148467] 'process raft request' (duration: 123.946986ms)","trace[201148467] 'compare' (duration: 17.545825ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:16.953554Z","caller":"traceutil/trace.go:171","msg":"trace[1083129580] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"124.707661ms","start":"2026-04-06T20:59:16.828809Z","end":"2026-04-06T20:59:16.953517Z","steps":["trace[1083129580] 'process raft request' (duration: 118.190372ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:18.891418Z","caller":"traceutil/trace.go:171","msg":"trace[1633664975] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"111.646525ms","start":"2026-04-06T20:59:18.779733Z","end":"2026-04-06T20:59:18.89138Z","steps":["trace[1633664975] 'process raft request' (duration: 108.840399ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:19.814074Z","caller":"traceutil/trace.go:171","msg":"trace[1992215661] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"139.728256ms","start":"2026-04-06T20:59:19.674309Z","end":"2026-04-06T20:59:19.814037Z","steps":["trace[1992215661] 'process raft request' (duration: 81.397765ms)","trace[1992215661] 'compare' (duration: 58.096059ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:19.814114Z","caller":"traceutil/trace.go:171","msg":"trace[1153547939] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"134.966398ms","start":"2026-04-06T20:59:19.679125Z","end":"2026-04-06T20:59:19.814092Z","steps":["trace[1153547939] 'process raft request' (duration: 134.819457ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:19.814073Z","caller":"traceutil/trace.go:171","msg":"trace[1656512090] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"105.737318ms","start":"2026-04-06T20:59:19.708313Z","end":"2026-04-06T20:59:19.81405Z","steps":["trace[1656512090] 'process raft request' (duration: 105.693018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.178144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.841884ms","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-04-06T20:59:20.178237Z","caller":"traceutil/trace.go:171","msg":"trace[1821434758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:323; }","duration":"117.992834ms","start":"2026-04-06T20:59:20.060225Z","end":"2026-04-06T20:59:20.178218Z","steps":["trace[1821434758] 'range keys from in-memory index tree' (duration: 117.711384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.178376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.082429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-06T20:59:20.178485Z","caller":"traceutil/trace.go:171","msg":"trace[895612240] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:323; }","duration":"261.26915ms","start":"2026-04-06T20:59:19.917196Z","end":"2026-04-06T20:59:20.178465Z","steps":["trace[895612240] 'range keys from in-memory index tree' (duration: 260.730456ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:20.642225Z","caller":"traceutil/trace.go:171","msg":"trace[379655838] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"112.04561ms","start":"2026-04-06T20:59:20.530153Z","end":"2026-04-06T20:59:20.642198Z","steps":["trace[379655838] 'process raft request' (duration: 111.94299ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:20.922112Z","caller":"traceutil/trace.go:171","msg":"trace[1651765995] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"391.749324ms","start":"2026-04-06T20:59:20.530342Z","end":"2026-04-06T20:59:20.922092Z","steps":["trace[1651765995] 'process raft request' (duration: 391.658183ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:20.922192Z","caller":"traceutil/trace.go:171","msg":"trace[568090661] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"391.791604ms","start":"2026-04-06T20:59:20.530361Z","end":"2026-04-06T20:59:20.922153Z","steps":["trace[568090661] 'process raft request' (duration: 391.660983ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:20.922211Z","caller":"traceutil/trace.go:171","msg":"trace[743806146] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:337; }","duration":"277.293269ms","start":"2026-04-06T20:59:20.644897Z","end":"2026-04-06T20:59:20.922191Z","steps":["trace[743806146] 'read index received' (duration: 276.998718ms)","trace[743806146] 'applied index is now lower than readState.Index' (duration: 293.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:20.922246Z","caller":"traceutil/trace.go:171","msg":"trace[841203537] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"364.195423ms","start":"2026-04-06T20:59:20.558032Z","end":"2026-04-06T20:59:20.922228Z","steps":["trace[841203537] 'process raft request' (duration: 364.028222ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:20.92229Z","caller":"traceutil/trace.go:171","msg":"trace[1587805567] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"284.47061ms","start":"2026-04-06T20:59:20.637773Z","end":"2026-04-06T20:59:20.922244Z","steps":["trace[1587805567] 'process raft request' (duration: 284.37489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.922333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:20.53035Z","time spent":"391.920545ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:20.922348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:20.557995Z","time spent":"364.305554ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:20.92237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.45439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-06T20:59:20.922404Z","caller":"traceutil/trace.go:171","msg":"trace[284052194] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:328; }","duration":"277.52124ms","start":"2026-04-06T20:59:20.644873Z","end":"2026-04-06T20:59:20.922394Z","steps":["trace[284052194] 'agreement among raft nodes before linearized reading' (duration: 277.44537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.922228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:20.530323Z","time spent":"391.856534ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:20.922127Z","caller":"traceutil/trace.go:171","msg":"trace[441022580] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"391.946825ms","start":"2026-04-06T20:59:20.530164Z","end":"2026-04-06T20:59:20.922111Z","steps":["trace[441022580] 'process raft request' (duration: 391.762194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.922474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:20.530145Z","time spent":"392.308107ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:20.922565Z","caller":"traceutil/trace.go:171","msg":"trace[1081661911] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"391.695743ms","start":"2026-04-06T20:59:20.530492Z","end":"2026-04-06T20:59:20.922188Z","steps":["trace[1081661911] 'process raft request' (duration: 391.549442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:20.922616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:20.530473Z","time spent":"392.121685ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:21.203436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.518019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:21.203514Z","caller":"traceutil/trace.go:171","msg":"trace[1186710343] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"274.081225ms","start":"2026-04-06T20:59:20.92942Z","end":"2026-04-06T20:59:21.203502Z","steps":["trace[1186710343] 'process raft request' (duration: 90.444395ms)","trace[1186710343] 'compare' (duration: 183.424019ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:21.204648Z","caller":"traceutil/trace.go:171","msg":"trace[1731895008] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"275.11743ms","start":"2026-04-06T20:59:20.929505Z","end":"2026-04-06T20:59:21.204622Z","steps":["trace[1731895008] 'process raft request' (duration: 274.943319ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:21.204699Z","caller":"traceutil/trace.go:171","msg":"trace[283194324] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"273.793084ms","start":"2026-04-06T20:59:20.930897Z","end":"2026-04-06T20:59:21.20469Z","steps":["trace[283194324] 'process raft request' (duration: 273.664293ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:21.204691Z","caller":"traceutil/trace.go:171","msg":"trace[1807277964] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"272.046582ms","start":"2026-04-06T20:59:20.932619Z","end":"2026-04-06T20:59:21.204666Z","steps":["trace[1807277964] 'process raft request' (duration: 271.989362ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:21.497676Z","caller":"traceutil/trace.go:171","msg":"trace[693445858] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"204.31116ms","start":"2026-04-06T20:59:21.293336Z","end":"2026-04-06T20:59:21.497647Z","steps":["trace[693445858] 'read index received' (duration: 111.597845ms)","trace[693445858] 'applied index is now lower than readState.Index' (duration: 92.712085ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:21.497837Z","caller":"traceutil/trace.go:171","msg":"trace[99920511] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"204.987533ms","start":"2026-04-06T20:59:21.292814Z","end":"2026-04-06T20:59:21.497801Z","steps":["trace[99920511] 'process raft request' (duration: 112.182677ms)","trace[99920511] 'compare' (duration: 92.487324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:21.497868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.498521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:21.498046Z","caller":"traceutil/trace.go:171","msg":"trace[1830378591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:335; }","duration":"204.716332ms","start":"2026-04-06T20:59:21.293309Z","end":"2026-04-06T20:59:21.498025Z","steps":["trace[1830378591] 'agreement among raft nodes before linearized reading' (duration: 204.445651ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:21.828499Z","caller":"traceutil/trace.go:171","msg":"trace[1739426524] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"246.647563ms","start":"2026-04-06T20:59:21.581826Z","end":"2026-04-06T20:59:21.828474Z","steps":["trace[1739426524] 'read index received' (duration: 246.426612ms)","trace[1739426524] 'applied index is now lower than readState.Index' (duration: 220.161µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:21.828561Z","caller":"traceutil/trace.go:171","msg":"trace[487326517] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"320.246309ms","start":"2026-04-06T20:59:21.508296Z","end":"2026-04-06T20:59:21.828542Z","steps":["trace[487326517] 'process raft request' (duration: 320.037858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:21.828737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.873534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:21.828851Z","caller":"traceutil/trace.go:171","msg":"trace[208715564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:337; }","duration":"247.059916ms","start":"2026-04-06T20:59:21.58176Z","end":"2026-04-06T20:59:21.82882Z","steps":["trace[208715564] 'agreement among raft nodes before linearized reading' (duration: 246.817365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:21.828834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:21.508281Z","time spent":"320.32487ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4303,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:22.710163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.769824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-06T20:59:22.710252Z","caller":"traceutil/trace.go:171","msg":"trace[1788019677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"202.905465ms","start":"2026-04-06T20:59:22.507326Z","end":"2026-04-06T20:59:22.710232Z","steps":["trace[1788019677] 'range keys from in-memory index tree' (duration: 202.217882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.710599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.76782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-06T20:59:22.710612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.916403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-06T20:59:22.710675Z","caller":"traceutil/trace.go:171","msg":"trace[1948767715] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:350; }","duration":"102.889291ms","start":"2026-04-06T20:59:22.607768Z","end":"2026-04-06T20:59:22.710657Z","steps":["trace[1948767715] 'range keys from in-memory index tree' (duration: 101.833414ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.710704Z","caller":"traceutil/trace.go:171","msg":"trace[1990063447] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"253.088064ms","start":"2026-04-06T20:59:22.457594Z","end":"2026-04-06T20:59:22.710682Z","steps":["trace[1990063447] 'range keys from in-memory index tree' (duration: 251.995908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.710754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.415255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-06T20:59:22.710836Z","caller":"traceutil/trace.go:171","msg":"trace[1747221651] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"152.527336ms","start":"2026-04-06T20:59:22.558282Z","end":"2026-04-06T20:59:22.710809Z","steps":["trace[1747221651] 'range keys from in-memory index tree' (duration: 151.359349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.710732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.888619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-06T20:59:22.711064Z","caller":"traceutil/trace.go:171","msg":"trace[1149201790] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:350; }","duration":"303.300182ms","start":"2026-04-06T20:59:22.407726Z","end":"2026-04-06T20:59:22.711026Z","steps":["trace[1149201790] 'range keys from in-memory index tree' (duration: 301.890793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.71112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:22.407697Z","time spent":"303.407722ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-04-06T20:59:22.856141Z","caller":"traceutil/trace.go:171","msg":"trace[1868294072] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"125.862363ms","start":"2026-04-06T20:59:22.730251Z","end":"2026-04-06T20:59:22.856114Z","steps":["trace[1868294072] 'read index received' (duration: 125.585182ms)","trace[1868294072] 'applied index is now lower than readState.Index' (duration: 275.901µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:22.856198Z","caller":"traceutil/trace.go:171","msg":"trace[482303680] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"138.770788ms","start":"2026-04-06T20:59:22.717382Z","end":"2026-04-06T20:59:22.856153Z","steps":["trace[482303680] 'process raft request' (duration: 138.503016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.856616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.311796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6957"} {"level":"info","ts":"2026-04-06T20:59:22.856697Z","caller":"traceutil/trace.go:171","msg":"trace[633906712] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:351; }","duration":"126.462756ms","start":"2026-04-06T20:59:22.730215Z","end":"2026-04-06T20:59:22.856677Z","steps":["trace[633906712] 'agreement among raft nodes before linearized reading' (duration: 126.029004ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.990085Z","caller":"traceutil/trace.go:171","msg":"trace[2022246404] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"125.579211ms","start":"2026-04-06T20:59:22.86448Z","end":"2026-04-06T20:59:22.990059Z","steps":["trace[2022246404] 'process raft request' (duration: 70.209603ms)","trace[2022246404] 'compare' (duration: 55.145487ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:22.990154Z","caller":"traceutil/trace.go:171","msg":"trace[533247096] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"105.981878ms","start":"2026-04-06T20:59:22.884149Z","end":"2026-04-06T20:59:22.990131Z","steps":["trace[533247096] 'process raft request' (duration: 105.939958ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.990393Z","caller":"traceutil/trace.go:171","msg":"trace[630415680] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"123.713381ms","start":"2026-04-06T20:59:22.866669Z","end":"2026-04-06T20:59:22.990382Z","steps":["trace[630415680] 'process raft request' (duration: 123.374499ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.990401Z","caller":"traceutil/trace.go:171","msg":"trace[2143743836] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"124.476475ms","start":"2026-04-06T20:59:22.8659Z","end":"2026-04-06T20:59:22.990376Z","steps":["trace[2143743836] 'process raft request' (duration: 124.081332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:22.990542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.775469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-04-06T20:59:22.990557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.79871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-06T20:59:22.991213Z","caller":"traceutil/trace.go:171","msg":"trace[73782638] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"129.490514ms","start":"2026-04-06T20:59:22.861711Z","end":"2026-04-06T20:59:22.991202Z","steps":["trace[73782638] 'agreement among raft nodes before linearized reading' (duration: 128.80112ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.990576Z","caller":"traceutil/trace.go:171","msg":"trace[2045966069] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"128.83525ms","start":"2026-04-06T20:59:22.861731Z","end":"2026-04-06T20:59:22.990566Z","steps":["trace[2045966069] 'agreement among raft nodes before linearized reading' (duration: 128.74458ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:22.990488Z","caller":"traceutil/trace.go:171","msg":"trace[2146579257] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:367; }","duration":"109.263318ms","start":"2026-04-06T20:59:22.881201Z","end":"2026-04-06T20:59:22.990465Z","steps":["trace[2146579257] 'read index received' (duration: 53.495567ms)","trace[2146579257] 'applied index is now lower than readState.Index' (duration: 55.762441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:22.990629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.890621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-06T20:59:22.99154Z","caller":"traceutil/trace.go:171","msg":"trace[2034561202] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"129.825416ms","start":"2026-04-06T20:59:22.861689Z","end":"2026-04-06T20:59:22.991515Z","steps":["trace[2034561202] 'agreement among raft nodes before linearized reading' (duration: 128.84737ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:23.420764Z","caller":"traceutil/trace.go:171","msg":"trace[730989425] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"207.863168ms","start":"2026-04-06T20:59:23.212861Z","end":"2026-04-06T20:59:23.420724Z","steps":["trace[730989425] 'process raft request' (duration: 132.232955ms)","trace[730989425] 'compare' (duration: 75.293301ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:23.421263Z","caller":"traceutil/trace.go:171","msg":"trace[1282826306] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:389; }","duration":"163.393713ms","start":"2026-04-06T20:59:23.257858Z","end":"2026-04-06T20:59:23.421251Z","steps":["trace[1282826306] 'read index received' (duration: 87.235558ms)","trace[1282826306] 'applied index is now lower than readState.Index' (duration: 76.157135ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:23.421334Z","caller":"traceutil/trace.go:171","msg":"trace[1700239686] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"205.512914ms","start":"2026-04-06T20:59:23.215807Z","end":"2026-04-06T20:59:23.42132Z","steps":["trace[1700239686] 'process raft request' (duration: 205.232052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:23.42159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.200922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-06T20:59:23.42157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.568764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-06T20:59:23.42166Z","caller":"traceutil/trace.go:171","msg":"trace[1810108770] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:376; }","duration":"114.276512ms","start":"2026-04-06T20:59:23.307344Z","end":"2026-04-06T20:59:23.42162Z","steps":["trace[1810108770] 'agreement among raft nodes before linearized reading' (duration: 114.185772ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:23.421662Z","caller":"traceutil/trace.go:171","msg":"trace[870006577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:376; }","duration":"163.805226ms","start":"2026-04-06T20:59:23.25783Z","end":"2026-04-06T20:59:23.421635Z","steps":["trace[870006577] 'agreement among raft nodes before linearized reading' (duration: 163.501804ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:23.421189Z","caller":"traceutil/trace.go:171","msg":"trace[185446423] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"206.448129ms","start":"2026-04-06T20:59:23.214694Z","end":"2026-04-06T20:59:23.421143Z","steps":["trace[185446423] 'process raft request' (duration: 206.172997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:23.796073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.430065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:23.79622Z","caller":"traceutil/trace.go:171","msg":"trace[1220586699] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"367.52927ms","start":"2026-04-06T20:59:23.428669Z","end":"2026-04-06T20:59:23.796198Z","steps":["trace[1220586699] 'process raft request' (duration: 173.907003ms)","trace[1220586699] 'compare' (duration: 193.229194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:23.796285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.428642Z","time spent":"367.61633ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:23.796666Z","caller":"traceutil/trace.go:171","msg":"trace[1317622981] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:392; }","duration":"260.77947ms","start":"2026-04-06T20:59:23.535862Z","end":"2026-04-06T20:59:23.796642Z","steps":["trace[1317622981] 'read index received' (duration: 66.754182ms)","trace[1317622981] 'applied index is now lower than readState.Index' (duration: 194.024358ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:23.796723Z","caller":"traceutil/trace.go:171","msg":"trace[48026797] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"366.375082ms","start":"2026-04-06T20:59:23.430333Z","end":"2026-04-06T20:59:23.796708Z","steps":["trace[48026797] 'process raft request' (duration: 366.229051ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:23.796744Z","caller":"traceutil/trace.go:171","msg":"trace[661937562] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"367.61439ms","start":"2026-04-06T20:59:23.429085Z","end":"2026-04-06T20:59:23.7967Z","steps":["trace[661937562] 'process raft request' (duration: 367.337909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:23.796869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.001191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6957"} {"level":"info","ts":"2026-04-06T20:59:23.796934Z","caller":"traceutil/trace.go:171","msg":"trace[848727543] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:380; }","duration":"261.107251ms","start":"2026-04-06T20:59:23.53581Z","end":"2026-04-06T20:59:23.796917Z","steps":["trace[848727543] 'agreement among raft nodes before linearized reading' (duration: 260.956411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:23.796941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.429062Z","time spent":"367.769101ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:23.79677Z","caller":"traceutil/trace.go:171","msg":"trace[2059224363] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"366.270652ms","start":"2026-04-06T20:59:23.430476Z","end":"2026-04-06T20:59:23.796747Z","steps":["trace[2059224363] 'process raft request' (duration: 366.124082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:23.797057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.430312Z","time spent":"366.534263ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:23.797084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.430466Z","time spent":"366.562184ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:24.240045Z","caller":"traceutil/trace.go:171","msg":"trace[1582562774] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"436.689868ms","start":"2026-04-06T20:59:23.803327Z","end":"2026-04-06T20:59:24.240017Z","steps":["trace[1582562774] 'process raft request' (duration: 389.137628ms)","trace[1582562774] 'compare' (duration: 47.106387ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:24.240159Z","caller":"traceutil/trace.go:171","msg":"trace[1025160245] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"362.807567ms","start":"2026-04-06T20:59:23.877326Z","end":"2026-04-06T20:59:24.240134Z","steps":["trace[1025160245] 'process raft request' (duration: 362.707567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.240229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.803311Z","time spent":"436.803499ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:24.240316Z","caller":"traceutil/trace.go:171","msg":"trace[1307953172] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"436.785649ms","start":"2026-04-06T20:59:23.803499Z","end":"2026-04-06T20:59:24.240285Z","steps":["trace[1307953172] 'read index received' (duration: 71.091576ms)","trace[1307953172] 'applied index is now lower than readState.Index' (duration: 365.691393ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:24.24039Z","caller":"traceutil/trace.go:171","msg":"trace[2022822317] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"434.638936ms","start":"2026-04-06T20:59:23.805738Z","end":"2026-04-06T20:59:24.240377Z","steps":["trace[2022822317] 'process raft request' (duration: 434.238054ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:24.240389Z","caller":"traceutil/trace.go:171","msg":"trace[986741989] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"434.670457ms","start":"2026-04-06T20:59:23.805698Z","end":"2026-04-06T20:59:24.240368Z","steps":["trace[986741989] 'process raft request' (duration: 434.212745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.240425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.877304Z","time spent":"363.020918ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:24.240445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.805727Z","time spent":"434.686797ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:24.240507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.99304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"warn","ts":"2026-04-06T20:59:24.240522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.805688Z","time spent":"434.746988ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:24.240559Z","caller":"traceutil/trace.go:171","msg":"trace[700605791] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:385; }","duration":"437.07143ms","start":"2026-04-06T20:59:23.803472Z","end":"2026-04-06T20:59:24.240543Z","steps":["trace[700605791] 'agreement among raft nodes before linearized reading' (duration: 436.96683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.240601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:23.80346Z","time spent":"437.128792ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":2131,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-04-06T20:59:24.240621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.784701ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:24.240649Z","caller":"traceutil/trace.go:171","msg":"trace[14348036] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:385; }","duration":"270.811141ms","start":"2026-04-06T20:59:23.969828Z","end":"2026-04-06T20:59:24.240639Z","steps":["trace[14348036] 'agreement among raft nodes before linearized reading' (duration: 270.72654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.725284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.999614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:24.725446Z","caller":"traceutil/trace.go:171","msg":"trace[1899861415] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"480.019498ms","start":"2026-04-06T20:59:24.245396Z","end":"2026-04-06T20:59:24.725415Z","steps":["trace[1899861415] 'process raft request' (duration: 116.785234ms)","trace[1899861415] 'compare' (duration: 362.824842ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:24.725464Z","caller":"traceutil/trace.go:171","msg":"trace[1383497117] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"476.754159ms","start":"2026-04-06T20:59:24.248697Z","end":"2026-04-06T20:59:24.725451Z","steps":["trace[1383497117] 'process raft request' (duration: 476.690939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.725586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:24.245349Z","time spent":"480.189359ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:24.725595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:24.248681Z","time spent":"476.85706ms","remote":"127.0.0.1:54160","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-04-06T20:59:24.725526Z","caller":"traceutil/trace.go:171","msg":"trace[1870193406] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"478.032497ms","start":"2026-04-06T20:59:24.247426Z","end":"2026-04-06T20:59:24.725459Z","steps":["trace[1870193406] 'read index received' (duration: 114.679552ms)","trace[1870193406] 'applied index is now lower than readState.Index' (duration: 363.348405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:24.725661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.213708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5315"} {"level":"info","ts":"2026-04-06T20:59:24.72572Z","caller":"traceutil/trace.go:171","msg":"trace[249488915] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:387; }","duration":"478.306729ms","start":"2026-04-06T20:59:24.2474Z","end":"2026-04-06T20:59:24.725707Z","steps":["trace[249488915] 'agreement among raft nodes before linearized reading' (duration: 478.190278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.725763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:24.247387Z","time spent":"478.367729ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5338,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-06T20:59:24.72584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.061511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:2278"} {"level":"info","ts":"2026-04-06T20:59:24.725927Z","caller":"traceutil/trace.go:171","msg":"trace[1414218877] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:387; }","duration":"477.171282ms","start":"2026-04-06T20:59:24.248732Z","end":"2026-04-06T20:59:24.725903Z","steps":["trace[1414218877] 'agreement among raft nodes before linearized reading' (duration: 477.015901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:24.726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:24.248716Z","time spent":"477.273852ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":2301,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-04-06T20:59:24.981309Z","caller":"traceutil/trace.go:171","msg":"trace[2059040600] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"247.554317ms","start":"2026-04-06T20:59:24.733671Z","end":"2026-04-06T20:59:24.981225Z","steps":["trace[2059040600] 'process raft request' (duration: 164.263443ms)","trace[2059040600] 'compare' (duration: 83.137533ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:25.256421Z","caller":"traceutil/trace.go:171","msg":"trace[1461534073] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"270.847893ms","start":"2026-04-06T20:59:24.98555Z","end":"2026-04-06T20:59:25.256398Z","steps":["trace[1461534073] 'read index received' (duration: 179.994319ms)","trace[1461534073] 'applied index is now lower than readState.Index' (duration: 90.852694ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:25.256479Z","caller":"traceutil/trace.go:171","msg":"trace[1764191625] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"521.090716ms","start":"2026-04-06T20:59:24.735362Z","end":"2026-04-06T20:59:25.256453Z","steps":["trace[1764191625] 'process raft request' (duration: 430.248582ms)","trace[1764191625] 'compare' (duration: 90.684913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:25.256676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.099435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5315"} {"level":"warn","ts":"2026-04-06T20:59:25.256749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:24.735342Z","time spent":"521.185606ms","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:25.256777Z","caller":"traceutil/trace.go:171","msg":"trace[1849583657] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:389; }","duration":"271.235605ms","start":"2026-04-06T20:59:24.985516Z","end":"2026-04-06T20:59:25.256751Z","steps":["trace[1849583657] 'agreement among raft nodes before linearized reading' (duration: 271.012573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:25.704389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.586969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T20:59:25.704482Z","caller":"traceutil/trace.go:171","msg":"trace[44301760] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"279.707182ms","start":"2026-04-06T20:59:25.424762Z","end":"2026-04-06T20:59:25.704469Z","steps":["trace[44301760] 'read index received' (duration: 139.973562ms)","trace[44301760] 'applied index is now lower than readState.Index' (duration: 139.73246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:25.70453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.783862ms","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-04-06T20:59:25.704534Z","caller":"traceutil/trace.go:171","msg":"trace[226867571] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"439.494546ms","start":"2026-04-06T20:59:25.265001Z","end":"2026-04-06T20:59:25.704495Z","steps":["trace[226867571] 'process raft request' (duration: 299.734596ms)","trace[226867571] 'compare' (duration: 139.431088ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:25.70455Z","caller":"traceutil/trace.go:171","msg":"trace[160974333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"279.836292ms","start":"2026-04-06T20:59:25.424705Z","end":"2026-04-06T20:59:25.704542Z","steps":["trace[160974333] 'agreement among raft nodes before linearized reading' (duration: 279.796132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:25.704814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:25.264962Z","time spent":"439.651198ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5129,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:25.94708Z","caller":"traceutil/trace.go:171","msg":"trace[1528239568] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"238.49815ms","start":"2026-04-06T20:59:25.708554Z","end":"2026-04-06T20:59:25.947052Z","steps":["trace[1528239568] 'process raft request' (duration: 139.321969ms)","trace[1528239568] 'compare' (duration: 99.05334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:29.027508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.945588ms","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-04-06T20:59:29.027601Z","caller":"traceutil/trace.go:171","msg":"trace[661322607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"229.093169ms","start":"2026-04-06T20:59:28.798487Z","end":"2026-04-06T20:59:29.027581Z","steps":["trace[661322607] 'range keys from in-memory index tree' (duration: 228.817607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822283632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-06T20:59:29.028265Z","caller":"traceutil/trace.go:171","msg":"trace[908912646] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:403; }","duration":"1.822394522s","start":"2026-04-06T20:59:27.20585Z","end":"2026-04-06T20:59:29.028245Z","steps":["trace[908912646] 'range keys from in-memory index tree' (duration: 1.822204581s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:27.205838Z","time spent":"1.822457432s","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-04-06T20:59:29.028533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793535699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4318"} {"level":"info","ts":"2026-04-06T20:59:29.028567Z","caller":"traceutil/trace.go:171","msg":"trace[1035597156] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:403; }","duration":"1.793591439s","start":"2026-04-06T20:59:27.23496Z","end":"2026-04-06T20:59:29.028552Z","steps":["trace[1035597156] 'range keys from in-memory index tree' (duration: 1.793461639s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:27.234943Z","time spent":"1.79364348s","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4341,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-06T20:59:29.028718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67597784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-06T20:59:29.028724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.056024ms","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-04-06T20:59:29.028742Z","caller":"traceutil/trace.go:171","msg":"trace[444678439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"1.676045351s","start":"2026-04-06T20:59:27.352689Z","end":"2026-04-06T20:59:29.028735Z","steps":["trace[444678439] 'range keys from in-memory index tree' (duration: 1.675691819s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:27.352666Z","time spent":"1.676097861s","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-06T20:59:29.028769Z","caller":"traceutil/trace.go:171","msg":"trace[1142971653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"617.138754ms","start":"2026-04-06T20:59:28.411617Z","end":"2026-04-06T20:59:29.028755Z","steps":["trace[1142971653] 'range keys from in-memory index tree' (duration: 616.856923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:28.411598Z","time spent":"617.210055ms","remote":"127.0.0.1:54160","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-04-06T20:59:29.028942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070175984s","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-04-06T20:59:29.028973Z","caller":"traceutil/trace.go:171","msg":"trace[2727311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"1.070245214s","start":"2026-04-06T20:59:27.958719Z","end":"2026-04-06T20:59:29.028965Z","steps":["trace[2727311] 'range keys from in-memory index tree' (duration: 1.069262119s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.028998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:27.958696Z","time spent":"1.070295534s","remote":"127.0.0.1:54160","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-04-06T20:59:29.369205Z","caller":"traceutil/trace.go:171","msg":"trace[2067530051] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"336.617711ms","start":"2026-04-06T20:59:29.032565Z","end":"2026-04-06T20:59:29.369182Z","steps":["trace[2067530051] 'process raft request' (duration: 336.35295ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:29.36941Z","caller":"traceutil/trace.go:171","msg":"trace[1545834221] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"336.657412ms","start":"2026-04-06T20:59:29.032735Z","end":"2026-04-06T20:59:29.369393Z","steps":["trace[1545834221] 'process raft request' (duration: 336.366921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.369436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:29.032538Z","time spent":"336.742103ms","remote":"127.0.0.1:54160","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-04-06T20:59:29.369545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:29.032724Z","time spent":"336.752773ms","remote":"127.0.0.1:54160","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-04-06T20:59:29.369694Z","caller":"traceutil/trace.go:171","msg":"trace[287889074] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"336.954264ms","start":"2026-04-06T20:59:29.032734Z","end":"2026-04-06T20:59:29.369689Z","steps":["trace[287889074] 'process raft request' (duration: 336.580942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.369726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:29.032704Z","time spent":"337.003034ms","remote":"127.0.0.1:54160","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-04-06T20:59:29.369758Z","caller":"traceutil/trace.go:171","msg":"trace[814010630] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"335.459505ms","start":"2026-04-06T20:59:29.034285Z","end":"2026-04-06T20:59:29.369744Z","steps":["trace[814010630] 'process raft request' (duration: 335.324634ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:29.36975Z","caller":"traceutil/trace.go:171","msg":"trace[1115362457] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"332.180556ms","start":"2026-04-06T20:59:29.037544Z","end":"2026-04-06T20:59:29.369725Z","steps":["trace[1115362457] 'process raft request' (duration: 332.132726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:29.369823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:29.034267Z","time spent":"335.521165ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:29.36984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:29.037535Z","time spent":"332.261037ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4303,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:29.371587Z","caller":"traceutil/trace.go:171","msg":"trace[953307893] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"111.744861ms","start":"2026-04-06T20:59:29.259816Z","end":"2026-04-06T20:59:29.371561Z","steps":["trace[953307893] 'process raft request' (duration: 111.57519ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:39.760587Z","caller":"traceutil/trace.go:171","msg":"trace[2078381395] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"318.065389ms","start":"2026-04-06T20:59:39.44248Z","end":"2026-04-06T20:59:39.760545Z","steps":["trace[2078381395] 'process raft request' (duration: 291.322191ms)","trace[2078381395] 'compare' (duration: 26.463015ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:39.760789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:39.44246Z","time spent":"318.223477ms","remote":"127.0.0.1:54160","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-04-06T20:59:39.764995Z","caller":"traceutil/trace.go:171","msg":"trace[1606532171] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"311.016936ms","start":"2026-04-06T20:59:39.453962Z","end":"2026-04-06T20:59:39.764979Z","steps":["trace[1606532171] 'read index received' (duration: 279.956219ms)","trace[1606532171] 'applied index is now lower than readState.Index' (duration: 31.059747ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:39.76507Z","caller":"traceutil/trace.go:171","msg":"trace[1447252914] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"321.037194ms","start":"2026-04-06T20:59:39.444008Z","end":"2026-04-06T20:59:39.765045Z","steps":["trace[1447252914] 'process raft request' (duration: 320.880366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:39.765129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.150753ms","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-04-06T20:59:39.765192Z","caller":"traceutil/trace.go:171","msg":"trace[1585946580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"311.237127ms","start":"2026-04-06T20:59:39.453941Z","end":"2026-04-06T20:59:39.765178Z","steps":["trace[1585946580] 'agreement among raft nodes before linearized reading' (duration: 311.111181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:39.765188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:39.44399Z","time spent":"321.133327ms","remote":"127.0.0.1:54160","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-04-06T20:59:39.765222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:39.453931Z","time spent":"311.28506ms","remote":"127.0.0.1:54160","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-04-06T20:59:43.636417Z","caller":"traceutil/trace.go:171","msg":"trace[820935146] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"193.417838ms","start":"2026-04-06T20:59:43.442983Z","end":"2026-04-06T20:59:43.6364Z","steps":["trace[820935146] 'process raft request' (duration: 193.318812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.177476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T20:59:50.515125Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.120683583s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T20:59:50.515613Z","caller":"traceutil/trace.go:171","msg":"trace[1983159148] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.121235056s","start":"2026-04-06T20:59:49.394354Z","end":"2026-04-06T20:59:50.515589Z","steps":["trace[1983159148] 'process raft request' (duration: 1.121046788s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.515851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.394337Z","time spent":"1.121341471s","remote":"127.0.0.1:53910","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-04-06T20:59:50.569976Z","caller":"traceutil/trace.go:171","msg":"trace[1691831594] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.174472312s","start":"2026-04-06T20:59:49.395467Z","end":"2026-04-06T20:59:50.56994Z","steps":["trace[1691831594] 'process raft request' (duration: 1.173429577s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.570167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.395459Z","time spent":"1.174635119s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570222Z","caller":"traceutil/trace.go:171","msg":"trace[89775796] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.160411789s","start":"2026-04-06T20:59:49.409757Z","end":"2026-04-06T20:59:50.570168Z","steps":["trace[89775796] 'process raft request' (duration: 1.16020633s)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:50.570265Z","caller":"traceutil/trace.go:171","msg":"trace[411725568] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.173715629s","start":"2026-04-06T20:59:49.396474Z","end":"2026-04-06T20:59:50.57019Z","steps":["trace[411725568] 'process raft request' (duration: 1.173405895s)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:50.570303Z","caller":"traceutil/trace.go:171","msg":"trace[200243581] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.160232871s","start":"2026-04-06T20:59:49.41006Z","end":"2026-04-06T20:59:50.570292Z","steps":["trace[200243581] 'process raft request' (duration: 1.160013242s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.570352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.410052Z","time spent":"1.160279773s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570299Z","caller":"traceutil/trace.go:171","msg":"trace[1931119129] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.160330065s","start":"2026-04-06T20:59:49.409885Z","end":"2026-04-06T20:59:50.570215Z","steps":["trace[1931119129] 'process raft request' (duration: 1.160150217s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.5704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.396454Z","time spent":"1.173880536s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.409749Z","time spent":"1.160576175s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570442Z","caller":"traceutil/trace.go:171","msg":"trace[2127103270] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.160632148s","start":"2026-04-06T20:59:49.409801Z","end":"2026-04-06T20:59:50.570433Z","steps":["trace[2127103270] 'process raft request' (duration: 1.160198s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.570486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.409797Z","time spent":"1.160672139s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:50.570535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.40988Z","time spent":"1.160527814s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570566Z","caller":"traceutil/trace.go:171","msg":"trace[289243824] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.160879558s","start":"2026-04-06T20:59:49.409679Z","end":"2026-04-06T20:59:50.570558Z","steps":["trace[289243824] 'process raft request' (duration: 1.1602463s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.570605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.409659Z","time spent":"1.16092756s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570618Z","caller":"traceutil/trace.go:171","msg":"trace[456016751] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.153523413s","start":"2026-04-06T20:59:49.417074Z","end":"2026-04-06T20:59:50.570598Z","steps":["trace[456016751] 'process raft request' (duration: 1.153035522s)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:50.570623Z","caller":"traceutil/trace.go:171","msg":"trace[2000971814] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.140839218s","start":"2026-04-06T20:59:49.429762Z","end":"2026-04-06T20:59:50.570601Z","steps":["trace[2000971814] 'process raft request' (duration: 1.140429931s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.570686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.417064Z","time spent":"1.153591075s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.429736Z","time spent":"1.140936623s","remote":"127.0.0.1:53910","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-04-06T20:59:50.570998Z","caller":"traceutil/trace.go:171","msg":"trace[2140690309] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.13784152s","start":"2026-04-06T20:59:49.433139Z","end":"2026-04-06T20:59:50.57098Z","steps":["trace[2140690309] 'process raft request' (duration: 1.137225174s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.571071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.43313Z","time spent":"1.137906202s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:50.571101Z","caller":"traceutil/trace.go:171","msg":"trace[411225123] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:548; }","duration":"901.377511ms","start":"2026-04-06T20:59:49.669695Z","end":"2026-04-06T20:59:50.571073Z","steps":["trace[411225123] 'read index received' (duration: 845.732693ms)","trace[411225123] 'applied index is now lower than readState.Index' (duration: 55.643698ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:50.571211Z","caller":"traceutil/trace.go:171","msg":"trace[501237170] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.016262146s","start":"2026-04-06T20:59:49.554939Z","end":"2026-04-06T20:59:50.571201Z","steps":["trace[501237170] 'process raft request' (duration: 1.01564856s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.571294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.554914Z","time spent":"1.016327739s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:50.571295Z","caller":"traceutil/trace.go:171","msg":"trace[28807443] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"415.930351ms","start":"2026-04-06T20:59:50.155296Z","end":"2026-04-06T20:59:50.571226Z","steps":["trace[28807443] 'process raft request' (duration: 415.544005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.571469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.155272Z","time spent":"416.119989ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T20:59:50.571522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.817899ms","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-04-06T20:59:50.571484Z","caller":"traceutil/trace.go:171","msg":"trace[1942405804] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"167.008345ms","start":"2026-04-06T20:59:50.404462Z","end":"2026-04-06T20:59:50.57147Z","steps":["trace[1942405804] 'process raft request' (duration: 166.527735ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:50.571575Z","caller":"traceutil/trace.go:171","msg":"trace[204901986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"901.912622ms","start":"2026-04-06T20:59:49.669651Z","end":"2026-04-06T20:59:50.571563Z","steps":["trace[204901986] 'agreement among raft nodes before linearized reading' (duration: 901.805148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.571618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.669528Z","time spent":"902.08147ms","remote":"127.0.0.1:54160","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-04-06T20:59:50.577892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.147752ms","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-04-06T20:59:50.577948Z","caller":"traceutil/trace.go:171","msg":"trace[619937806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"757.235025ms","start":"2026-04-06T20:59:49.820699Z","end":"2026-04-06T20:59:50.577934Z","steps":["trace[619937806] 'agreement among raft nodes before linearized reading' (duration: 757.134101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.57798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.820677Z","time spent":"757.295248ms","remote":"127.0.0.1:54160","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-04-06T20:59:50.578146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.16537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:50.57818Z","caller":"traceutil/trace.go:171","msg":"trace[341797672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"386.232452ms","start":"2026-04-06T20:59:50.19194Z","end":"2026-04-06T20:59:50.578172Z","steps":["trace[341797672] 'agreement among raft nodes before linearized reading' (duration: 386.18453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.578207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.191923Z","time spent":"386.278404ms","remote":"127.0.0.1:53854","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-04-06T20:59:50.578355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.73061ms","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-04-06T20:59:50.578389Z","caller":"traceutil/trace.go:171","msg":"trace[1616277200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"682.795224ms","start":"2026-04-06T20:59:49.895586Z","end":"2026-04-06T20:59:50.578381Z","steps":["trace[1616277200] 'agreement among raft nodes before linearized reading' (duration: 682.73599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.578415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.895567Z","time spent":"682.842466ms","remote":"127.0.0.1:54160","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-04-06T20:59:50.578615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.197881ms","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-04-06T20:59:50.578708Z","caller":"traceutil/trace.go:171","msg":"trace[1619888583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"683.326226ms","start":"2026-04-06T20:59:49.895368Z","end":"2026-04-06T20:59:50.578694Z","steps":["trace[1619888583] 'agreement among raft nodes before linearized reading' (duration: 683.168869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:50.578791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:49.895345Z","time spent":"683.43523ms","remote":"127.0.0.1:54160","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-04-06T20:59:51.767412Z","caller":"traceutil/trace.go:171","msg":"trace[558941500] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"103.780334ms","start":"2026-04-06T20:59:51.663607Z","end":"2026-04-06T20:59:51.767387Z","steps":["trace[558941500] 'read index received' (duration: 81.880707ms)","trace[558941500] 'applied index is now lower than readState.Index' (duration: 21.898747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:51.767584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.957212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-06T20:59:51.767609Z","caller":"traceutil/trace.go:171","msg":"trace[1529966041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:603; }","duration":"104.034224ms","start":"2026-04-06T20:59:51.663565Z","end":"2026-04-06T20:59:51.7676Z","steps":["trace[1529966041] 'agreement among raft nodes before linearized reading' (duration: 103.904539ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.767839Z","caller":"traceutil/trace.go:171","msg":"trace[995740325] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"120.17024ms","start":"2026-04-06T20:59:51.647631Z","end":"2026-04-06T20:59:51.767801Z","steps":["trace[995740325] 'process raft request' (duration: 97.941839ms)","trace[995740325] 'compare' (duration: 21.703059ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:51.979441Z","caller":"traceutil/trace.go:171","msg":"trace[95973326] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"173.035023ms","start":"2026-04-06T20:59:51.80638Z","end":"2026-04-06T20:59:51.979415Z","steps":["trace[95973326] 'process raft request' (duration: 95.937665ms)","trace[95973326] 'compare' (duration: 76.779755ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:51.989888Z","caller":"traceutil/trace.go:171","msg":"trace[419458427] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"159.886573ms","start":"2026-04-06T20:59:51.829987Z","end":"2026-04-06T20:59:51.989873Z","steps":["trace[419458427] 'read index received' (duration: 72.424412ms)","trace[419458427] 'applied index is now lower than readState.Index' (duration: 87.461711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:51.990049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.045859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-06T20:59:51.990072Z","caller":"traceutil/trace.go:171","msg":"trace[1080989441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"160.109192ms","start":"2026-04-06T20:59:51.829957Z","end":"2026-04-06T20:59:51.990066Z","steps":["trace[1080989441] 'agreement among raft nodes before linearized reading' (duration: 159.973726ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.990447Z","caller":"traceutil/trace.go:171","msg":"trace[303270998] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"176.043298ms","start":"2026-04-06T20:59:51.814398Z","end":"2026-04-06T20:59:51.990441Z","steps":["trace[303270998] 'process raft request' (duration: 175.317377ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:52.717597Z","caller":"traceutil/trace.go:171","msg":"trace[975662517] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"101.175404ms","start":"2026-04-06T20:59:52.616399Z","end":"2026-04-06T20:59:52.717574Z","steps":["trace[975662517] 'read index received' (duration: 100.965966ms)","trace[975662517] 'applied index is now lower than readState.Index' (duration: 208.618µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T20:59:52.717898Z","caller":"traceutil/trace.go:171","msg":"trace[207253481] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"120.792116ms","start":"2026-04-06T20:59:52.597094Z","end":"2026-04-06T20:59:52.717886Z","steps":["trace[207253481] 'process raft request' (duration: 120.327847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:52.7183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.880903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:52.718345Z","caller":"traceutil/trace.go:171","msg":"trace[851141969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:608; }","duration":"101.960216ms","start":"2026-04-06T20:59:52.616374Z","end":"2026-04-06T20:59:52.718334Z","steps":["trace[851141969] 'agreement among raft nodes before linearized reading' (duration: 101.861232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:52.718534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T20:59:52.718569Z","caller":"traceutil/trace.go:171","msg":"trace[803413716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:608; }","duration":"102.179326ms","start":"2026-04-06T20:59:52.61638Z","end":"2026-04-06T20:59:52.718559Z","steps":["trace[803413716] 'agreement among raft nodes before linearized reading' (duration: 102.130654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:58.255861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.63398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5t4v\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-06T20:59:58.255951Z","caller":"traceutil/trace.go:171","msg":"trace[1123013051] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5t4v; range_end:; response_count:1; response_revision:639; }","duration":"635.763594ms","start":"2026-04-06T20:59:57.620165Z","end":"2026-04-06T20:59:58.255929Z","steps":["trace[1123013051] 'range keys from in-memory index tree' (duration: 635.433602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:58.256706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:57.620145Z","time spent":"636.522513ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-c5t4v\" "} {"level":"warn","ts":"2026-04-06T20:59:58.69348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299450,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T20:59:58.699844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053302816s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T20:59:58.701865Z","caller":"traceutil/trace.go:171","msg":"trace[938972731] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.056704758s","start":"2026-04-06T20:59:57.645126Z","end":"2026-04-06T20:59:58.70183Z","steps":["trace[938972731] 'process raft request' (duration: 1.055569434s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:58.702102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:57.645051Z","time spent":"1.056926656s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:00:00.142125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.850117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:00:00.142287Z","caller":"traceutil/trace.go:171","msg":"trace[1697757180] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.378307192s","start":"2026-04-06T20:59:58.763965Z","end":"2026-04-06T21:00:00.142272Z","steps":["trace[1697757180] 'process raft request' (duration: 1.37824934s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:00.142342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.763936Z","time spent":"1.378383765s","remote":"127.0.0.1:54126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:00:00.142454Z","caller":"traceutil/trace.go:171","msg":"trace[154168384] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"1.949565566s","start":"2026-04-06T20:59:58.192869Z","end":"2026-04-06T21:00:00.142434Z","steps":["trace[154168384] 'read index received' (duration: 507.481857ms)","trace[154168384] 'applied index is now lower than readState.Index' (duration: 1.442082589s)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:00:00.142586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.949709302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:00.142617Z","caller":"traceutil/trace.go:171","msg":"trace[2061881462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.949770785s","start":"2026-04-06T20:59:58.192836Z","end":"2026-04-06T21:00:00.142606Z","steps":["trace[2061881462] 'agreement among raft nodes before linearized reading' (duration: 1.949701122s)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:00.142572Z","caller":"traceutil/trace.go:171","msg":"trace[195681526] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.872446617s","start":"2026-04-06T20:59:58.270074Z","end":"2026-04-06T21:00:00.142521Z","steps":["trace[195681526] 'process raft request' (duration: 982.128731ms)","trace[195681526] 'compare' (duration: 889.513975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:00:00.142648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.192795Z","time spent":"1.949847317s","remote":"127.0.0.1:53858","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-04-06T21:00:00.143066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.27005Z","time spent":"1.872620783s","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:00:00.143203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310509991s","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-04-06T21:00:00.143209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310924107s","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-04-06T21:00:00.143239Z","caller":"traceutil/trace.go:171","msg":"trace[1984773751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.310579153s","start":"2026-04-06T20:59:58.832649Z","end":"2026-04-06T21:00:00.143228Z","steps":["trace[1984773751] 'agreement among raft nodes before linearized reading' (duration: 1.310444578s)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:00.143256Z","caller":"traceutil/trace.go:171","msg":"trace[267552995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.311011389s","start":"2026-04-06T20:59:58.83223Z","end":"2026-04-06T21:00:00.143241Z","steps":["trace[267552995] 'agreement among raft nodes before linearized reading' (duration: 1.310865864s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:00.143268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.832632Z","time spent":"1.310628765s","remote":"127.0.0.1:54160","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-04-06T21:00:00.143253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310469119s","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-04-06T21:00:00.143286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.832205Z","time spent":"1.311073962s","remote":"127.0.0.1:54160","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-04-06T21:00:00.143316Z","caller":"traceutil/trace.go:171","msg":"trace[1858414078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"1.310550442s","start":"2026-04-06T20:59:58.83275Z","end":"2026-04-06T21:00:00.1433Z","steps":["trace[1858414078] 'agreement among raft nodes before linearized reading' (duration: 1.310343094s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:00.143356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.832745Z","time spent":"1.310602543s","remote":"127.0.0.1:54160","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-04-06T21:00:00.143431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310382145s","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-04-06T21:00:00.143461Z","caller":"traceutil/trace.go:171","msg":"trace[734774426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"1.310419607s","start":"2026-04-06T20:59:58.833031Z","end":"2026-04-06T21:00:00.143451Z","steps":["trace[734774426] 'agreement among raft nodes before linearized reading' (duration: 1.310338464s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:00.143487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:58.833026Z","time spent":"1.310454658s","remote":"127.0.0.1:54160","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-04-06T21:00:01.693046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:02.193475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:02.693622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:02.993467Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.76059286s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T21:00:02.993853Z","caller":"traceutil/trace.go:171","msg":"trace[1740435463] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"2.769739944s","start":"2026-04-06T21:00:00.224083Z","end":"2026-04-06T21:00:02.993823Z","steps":["trace[1740435463] 'process raft request' (duration: 2.769554777s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:02.993995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:00.224066Z","time spent":"2.769853038s","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:00:03.090608Z","caller":"traceutil/trace.go:171","msg":"trace[1983802440] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"2.331817586s","start":"2026-04-06T21:00:00.758766Z","end":"2026-04-06T21:00:03.090583Z","steps":["trace[1983802440] 'process raft request' (duration: 2.33163942s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.090751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:00.758728Z","time spent":"2.33194687s","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:00:03.091007Z","caller":"traceutil/trace.go:171","msg":"trace[360346001] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.197684698s","start":"2026-04-06T21:00:00.893279Z","end":"2026-04-06T21:00:03.090963Z","steps":["trace[360346001] 'process raft request' (duration: 2.197266543s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.091212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:00.893255Z","time spent":"2.197847114s","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:00:03.098504Z","caller":"traceutil/trace.go:171","msg":"trace[1866923987] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:674; }","duration":"1.90565344s","start":"2026-04-06T21:00:01.192833Z","end":"2026-04-06T21:00:03.098487Z","steps":["trace[1866923987] 'read index received' (duration: 1.800952179s)","trace[1866923987] 'applied index is now lower than readState.Index' (duration: 104.700151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:00:03.098616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905781935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:03.098653Z","caller":"traceutil/trace.go:171","msg":"trace[706482577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.905879929s","start":"2026-04-06T21:00:01.192764Z","end":"2026-04-06T21:00:03.098644Z","steps":["trace[706482577] 'agreement among raft nodes before linearized reading' (duration: 1.905819397s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.098682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:01.192745Z","time spent":"1.90593068s","remote":"127.0.0.1:53854","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-04-06T21:00:03.099451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.802782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-06T21:00:03.099481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.856913ms","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-04-06T21:00:03.0995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.321658ms","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-04-06T21:00:03.09956Z","caller":"traceutil/trace.go:171","msg":"trace[1621088304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"882.903614ms","start":"2026-04-06T21:00:02.21661Z","end":"2026-04-06T21:00:03.099513Z","steps":["trace[1621088304] 'agreement among raft nodes before linearized reading' (duration: 882.712308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.099657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:02.216597Z","time spent":"883.04953ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T21:00:03.09961Z","caller":"traceutil/trace.go:171","msg":"trace[2060655743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"896.488325ms","start":"2026-04-06T21:00:02.203097Z","end":"2026-04-06T21:00:03.099585Z","steps":["trace[2060655743] 'agreement among raft nodes before linearized reading' (duration: 896.192004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.099903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:02.203074Z","time spent":"896.817356ms","remote":"127.0.0.1:54160","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-04-06T21:00:03.099551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.219416ms","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-04-06T21:00:03.100072Z","caller":"traceutil/trace.go:171","msg":"trace[1649886651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"883.779626ms","start":"2026-04-06T21:00:02.216282Z","end":"2026-04-06T21:00:03.100061Z","steps":["trace[1649886651] 'agreement among raft nodes before linearized reading' (duration: 883.213816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.100127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:02.216262Z","time spent":"883.854699ms","remote":"127.0.0.1:54160","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-04-06T21:00:03.099539Z","caller":"traceutil/trace.go:171","msg":"trace[1884738683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"882.940006ms","start":"2026-04-06T21:00:02.216586Z","end":"2026-04-06T21:00:03.099526Z","steps":["trace[1884738683] 'agreement among raft nodes before linearized reading' (duration: 882.76963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:03.100281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:02.216572Z","time spent":"883.697384ms","remote":"127.0.0.1:54160","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-04-06T21:00:05.311922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.213016ms","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-04-06T21:00:05.312016Z","caller":"traceutil/trace.go:171","msg":"trace[1264347147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"177.360131ms","start":"2026-04-06T21:00:05.134632Z","end":"2026-04-06T21:00:05.311992Z","steps":["trace[1264347147] 'range keys from in-memory index tree' (duration: 177.020509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:05.312083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.871244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T21:00:05.312193Z","caller":"traceutil/trace.go:171","msg":"trace[558947188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"177.015959ms","start":"2026-04-06T21:00:05.135156Z","end":"2026-04-06T21:00:05.312172Z","steps":["trace[558947188] 'range keys from in-memory index tree' (duration: 176.708098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:05.312274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.371637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:05.312321Z","caller":"traceutil/trace.go:171","msg":"trace[401829887] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:690; }","duration":"308.44646ms","start":"2026-04-06T21:00:05.003858Z","end":"2026-04-06T21:00:05.312305Z","steps":["trace[401829887] 'range keys from in-memory index tree' (duration: 308.294034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:05.312357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:05.003838Z","time spent":"308.509552ms","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-06T21:00:05.312481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.150593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-06T21:00:05.312646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.555769ms","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-04-06T21:00:05.312677Z","caller":"traceutil/trace.go:171","msg":"trace[1554415656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"177.345311ms","start":"2026-04-06T21:00:05.135297Z","end":"2026-04-06T21:00:05.312642Z","steps":["trace[1554415656] 'range keys from in-memory index tree' (duration: 177.04721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:05.312636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.649272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:05.312721Z","caller":"traceutil/trace.go:171","msg":"trace[493948876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"188.673314ms","start":"2026-04-06T21:00:05.124027Z","end":"2026-04-06T21:00:05.312701Z","steps":["trace[493948876] 'range keys from in-memory index tree' (duration: 188.383673ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:05.312898Z","caller":"traceutil/trace.go:171","msg":"trace[1385355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"120.033355ms","start":"2026-04-06T21:00:05.192832Z","end":"2026-04-06T21:00:05.312769Z","steps":["trace[1385355] 'range keys from in-memory index tree' (duration: 119.559268ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:07.967601Z","caller":"traceutil/trace.go:171","msg":"trace[433546908] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"129.375994ms","start":"2026-04-06T21:00:07.838191Z","end":"2026-04-06T21:00:07.967566Z","steps":["trace[433546908] 'process raft request' (duration: 129.227329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:23.935386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:24.435896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:24.936309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807445027299820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T21:00:24.962483Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.538011438s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T21:00:24.962987Z","caller":"traceutil/trace.go:171","msg":"trace[1809133888] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.538608806s","start":"2026-04-06T21:00:23.424353Z","end":"2026-04-06T21:00:24.962961Z","steps":["trace[1809133888] 'process raft request' (duration: 1.538392909s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.963163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.424322Z","time spent":"1.53874443s","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:00:24.974521Z","caller":"traceutil/trace.go:171","msg":"trace[1619891814] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:855; }","duration":"1.539604611s","start":"2026-04-06T21:00:23.434897Z","end":"2026-04-06T21:00:24.974501Z","steps":["trace[1619891814] 'read index received' (duration: 1.527861461s)","trace[1619891814] 'applied index is now lower than readState.Index' (duration: 11.74233ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:00:24.974712Z","caller":"traceutil/trace.go:171","msg":"trace[604539669] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.212675195s","start":"2026-04-06T21:00:23.762024Z","end":"2026-04-06T21:00:24.974699Z","steps":["trace[604539669] 'process raft request' (duration: 1.212436868s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.974833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.762Z","time spent":"1.212760978s","remote":"127.0.0.1:54126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:00:24.974873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539487468s","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-04-06T21:00:24.974925Z","caller":"traceutil/trace.go:171","msg":"trace[2134532333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"1.53956646s","start":"2026-04-06T21:00:23.435345Z","end":"2026-04-06T21:00:24.974912Z","steps":["trace[2134532333] 'agreement among raft nodes before linearized reading' (duration: 1.539415496s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.974931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240135207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T21:00:24.974957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.435336Z","time spent":"1.539614842s","remote":"127.0.0.1:54160","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-04-06T21:00:24.974993Z","caller":"traceutil/trace.go:171","msg":"trace[49988589] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:823; }","duration":"1.240209249s","start":"2026-04-06T21:00:23.73475Z","end":"2026-04-06T21:00:24.974959Z","steps":["trace[49988589] 'agreement among raft nodes before linearized reading' (duration: 1.240143208s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.734736Z","time spent":"1.240285881s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-06T21:00:24.975108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240412045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.975138Z","caller":"traceutil/trace.go:171","msg":"trace[776329151] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:823; }","duration":"1.240469667s","start":"2026-04-06T21:00:23.734659Z","end":"2026-04-06T21:00:24.975129Z","steps":["trace[776329151] 'agreement among raft nodes before linearized reading' (duration: 1.240425546s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.734642Z","time spent":"1.240512808s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-04-06T21:00:24.975251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540351172s","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-04-06T21:00:24.975269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255556497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.975291Z","caller":"traceutil/trace.go:171","msg":"trace[1340384068] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:823; }","duration":"1.255609068s","start":"2026-04-06T21:00:23.719675Z","end":"2026-04-06T21:00:24.975284Z","steps":["trace[1340384068] 'agreement among raft nodes before linearized reading' (duration: 1.255570757s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.719665Z","time spent":"1.255645309s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-06T21:00:24.975376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315107065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.975424Z","caller":"traceutil/trace.go:171","msg":"trace[511604] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:823; }","duration":"1.315191527s","start":"2026-04-06T21:00:23.660223Z","end":"2026-04-06T21:00:24.975414Z","steps":["trace[511604] 'agreement among raft nodes before linearized reading' (duration: 1.315080034s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.660197Z","time spent":"1.31525955s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-04-06T21:00:24.975519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53719691s","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-04-06T21:00:24.97555Z","caller":"traceutil/trace.go:171","msg":"trace[285234742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"1.537244072s","start":"2026-04-06T21:00:23.438298Z","end":"2026-04-06T21:00:24.975542Z","steps":["trace[285234742] 'agreement among raft nodes before linearized reading' (duration: 1.5371853s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.438288Z","time spent":"1.537280243s","remote":"127.0.0.1:54160","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-04-06T21:00:24.975574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217181516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:00:24.975289Z","caller":"traceutil/trace.go:171","msg":"trace[1934424533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"1.540429125s","start":"2026-04-06T21:00:23.434851Z","end":"2026-04-06T21:00:24.97528Z","steps":["trace[1934424533] 'agreement among raft nodes before linearized reading' (duration: 1.540334392s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.43483Z","time spent":"1.540878708s","remote":"127.0.0.1:54160","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-04-06T21:00:24.975518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.221874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:00:24.975615Z","caller":"traceutil/trace.go:171","msg":"trace[1844695850] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:823; }","duration":"1.217241818s","start":"2026-04-06T21:00:23.758362Z","end":"2026-04-06T21:00:24.975604Z","steps":["trace[1844695850] 'agreement among raft nodes before linearized reading' (duration: 1.217171246s)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:24.975798Z","caller":"traceutil/trace.go:171","msg":"trace[1172510604] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:823; }","duration":"972.502722ms","start":"2026-04-06T21:00:24.003287Z","end":"2026-04-06T21:00:24.97579Z","steps":["trace[1172510604] 'agreement among raft nodes before linearized reading' (duration: 972.199093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.97583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.758351Z","time spent":"1.217460664s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-06T21:00:24.975784Z","caller":"traceutil/trace.go:171","msg":"trace[345081263] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.454007381s","start":"2026-04-06T21:00:23.521759Z","end":"2026-04-06T21:00:24.975766Z","steps":["trace[345081263] 'process raft request' (duration: 1.45254578s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.975986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.521731Z","time spent":"1.454142705s","remote":"127.0.0.1:54160","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-04-06T21:00:24.976102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225676804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T21:00:24.975467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.355939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.976144Z","caller":"traceutil/trace.go:171","msg":"trace[605470369] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:823; }","duration":"1.225730365s","start":"2026-04-06T21:00:23.750403Z","end":"2026-04-06T21:00:24.976134Z","steps":["trace[605470369] 'agreement among raft nodes before linearized reading' (duration: 1.225665053s)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:24.976167Z","caller":"traceutil/trace.go:171","msg":"trace[1657243118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"528.056479ms","start":"2026-04-06T21:00:24.4481Z","end":"2026-04-06T21:00:24.976157Z","steps":["trace[1657243118] 'agreement among raft nodes before linearized reading' (duration: 527.343338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.976181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.7504Z","time spent":"1.225771496s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-06T21:00:24.9762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.447899Z","time spent":"528.293096ms","remote":"127.0.0.1:53854","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-04-06T21:00:24.976242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315938999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.976273Z","caller":"traceutil/trace.go:171","msg":"trace[31829308] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:823; }","duration":"1.315984741s","start":"2026-04-06T21:00:23.66028Z","end":"2026-04-06T21:00:24.976265Z","steps":["trace[31829308] 'agreement among raft nodes before linearized reading' (duration: 1.315936489s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.976297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.660274Z","time spent":"1.316015071s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-06T21:00:24.97547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537110678s","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-04-06T21:00:24.976338Z","caller":"traceutil/trace.go:171","msg":"trace[1116826204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"1.537984323s","start":"2026-04-06T21:00:23.438339Z","end":"2026-04-06T21:00:24.976324Z","steps":["trace[1116826204] 'agreement among raft nodes before linearized reading' (duration: 1.537080458s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.976385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.438334Z","time spent":"1.538039975s","remote":"127.0.0.1:54160","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-04-06T21:00:24.976395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226038894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T21:00:24.976417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257655978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.976773Z","caller":"traceutil/trace.go:171","msg":"trace[1256259210] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:823; }","duration":"1.258035109s","start":"2026-04-06T21:00:23.718722Z","end":"2026-04-06T21:00:24.976757Z","steps":["trace[1256259210] 'agreement among raft nodes before linearized reading' (duration: 1.257674298s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.976808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.718701Z","time spent":"1.25809767s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-06T21:00:24.975845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.003261Z","time spent":"972.576754ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-06T21:00:24.976426Z","caller":"traceutil/trace.go:171","msg":"trace[1909219724] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:823; }","duration":"1.226085945s","start":"2026-04-06T21:00:23.750331Z","end":"2026-04-06T21:00:24.976417Z","steps":["trace[1909219724] 'agreement among raft nodes before linearized reading' (duration: 1.226035864s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.976971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.750321Z","time spent":"1.226641012s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-04-06T21:00:24.976473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226107676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.977061Z","caller":"traceutil/trace.go:171","msg":"trace[1339855522] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:823; }","duration":"1.226711084s","start":"2026-04-06T21:00:23.750343Z","end":"2026-04-06T21:00:24.977054Z","steps":["trace[1339855522] 'agreement among raft nodes before linearized reading' (duration: 1.226108946s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.97709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.750336Z","time spent":"1.226744855s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-04-06T21:00:24.976493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316064632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.977158Z","caller":"traceutil/trace.go:171","msg":"trace[1917132114] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:823; }","duration":"1.316754484s","start":"2026-04-06T21:00:23.660393Z","end":"2026-04-06T21:00:24.977148Z","steps":["trace[1917132114] 'agreement among raft nodes before linearized reading' (duration: 1.316056563s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.977202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.660387Z","time spent":"1.316800745s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-04-06T21:00:24.976507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241168326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.977311Z","caller":"traceutil/trace.go:171","msg":"trace[174094860] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:823; }","duration":"1.241985621s","start":"2026-04-06T21:00:23.735318Z","end":"2026-04-06T21:00:24.977303Z","steps":["trace[174094860] 'agreement among raft nodes before linearized reading' (duration: 1.241170637s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.977342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.73531Z","time spent":"1.242022802s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-04-06T21:00:24.975785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256805324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:24.977421Z","caller":"traceutil/trace.go:171","msg":"trace[1538466424] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:823; }","duration":"1.258446001s","start":"2026-04-06T21:00:23.718964Z","end":"2026-04-06T21:00:24.97741Z","steps":["trace[1538466424] 'agreement among raft nodes before linearized reading' (duration: 1.256731431s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.977669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:23.718941Z","time spent":"1.258586156s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-06T21:02:22.164916Z","caller":"traceutil/trace.go:171","msg":"trace[546616411] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"215.896055ms","start":"2026-04-06T21:02:21.948983Z","end":"2026-04-06T21:02:22.164879Z","steps":["trace[546616411] 'process raft request' (duration: 215.627371ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:22.178752Z","caller":"traceutil/trace.go:171","msg":"trace[141828681] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"229.112915ms","start":"2026-04-06T21:02:21.94961Z","end":"2026-04-06T21:02:22.178723Z","steps":["trace[141828681] 'process raft request' (duration: 228.906682ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:22.179226Z","caller":"traceutil/trace.go:171","msg":"trace[2074203739] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"228.364025ms","start":"2026-04-06T21:02:21.950848Z","end":"2026-04-06T21:02:22.179212Z","steps":["trace[2074203739] 'process raft request' (duration: 227.833828ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:52.682151Z","caller":"traceutil/trace.go:171","msg":"trace[2108069847] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"179.786839ms","start":"2026-04-06T21:02:52.502339Z","end":"2026-04-06T21:02:52.682126Z","steps":["trace[2108069847] 'process raft request' (duration: 179.638757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:52.934183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.857215ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-06T21:02:52.934945Z","caller":"traceutil/trace.go:171","msg":"trace[1160319806] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"292.910644ms","start":"2026-04-06T21:02:52.642016Z","end":"2026-04-06T21:02:52.934926Z","steps":["trace[1160319806] 'process raft request' (duration: 292.274737ms)"],"step_count":1}