{"level":"warn","ts":"2026-04-26T02:37:08.551543Z","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-26T02:37:08.551657Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-26T02:37:08.551739Z","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-26T02:37:08.551759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-26T02:37:08.551783Z","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-26T02:37:08.552643Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-26T02:37:08.552768Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-26T02:37:08.557644Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.409165ms"} {"level":"info","ts":"2026-04-26T02:37:08.564384Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-26T02:37:08.564458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-26T02:37:08.564482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-26T02:37:08.5645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-26T02:37:08.564513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-26T02:37:08.564555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-26T02:37:08.570486Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-26T02:37:08.572263Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-26T02:37:08.573566Z","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-26T02:37:08.577557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-26T02:37:08.579022Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-26T02:37:08.579134Z","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-26T02:37:08.579172Z","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-26T02:37:08.579182Z","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-26T02:37:08.580531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-26T02:37:08.58087Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-26T02:37:08.585203Z","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-26T02:37:08.58534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-26T02:37:08.585366Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-26T02:37:08.585433Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-26T02:37:08.585447Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-26T02:37:08.966551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-26T02:37:08.966616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-26T02:37:08.966646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-26T02:37:08.966699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-26T02:37:08.966708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-26T02:37:08.966716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-26T02:37:08.966729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-26T02:37:09.0161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T02:37:09.016402Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-26T02:37:09.016397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T02:37:09.016528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T02:37:09.016979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-26T02:37:09.017085Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-26T02:37:09.019147Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T02:37:09.019224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T02:37:09.019254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T02:37:09.019873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-26T02:37:09.020726Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-26T02:37:26.993211Z","caller":"traceutil/trace.go:171","msg":"trace[544460431] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"113.245977ms","start":"2026-04-26T02:37:26.879937Z","end":"2026-04-26T02:37:26.993183Z","steps":["trace[544460431] 'read index received' (duration: 113.025385ms)","trace[544460431] 'applied index is now lower than readState.Index' (duration: 219.432µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:37:26.993315Z","caller":"traceutil/trace.go:171","msg":"trace[1567116620] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"318.002168ms","start":"2026-04-26T02:37:26.675282Z","end":"2026-04-26T02:37:26.993285Z","steps":["trace[1567116620] 'process raft request' (duration: 317.715426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:26.993569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.55199ms","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-26T02:37:26.993708Z","caller":"traceutil/trace.go:171","msg":"trace[1651433433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"113.796382ms","start":"2026-04-26T02:37:26.87989Z","end":"2026-04-26T02:37:26.993687Z","steps":["trace[1651433433] 'agreement among raft nodes before linearized reading' (duration: 113.440309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:26.99409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:26.675252Z","time spent":"318.20061ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:37:35.617349Z","caller":"traceutil/trace.go:171","msg":"trace[72965087] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"131.24695ms","start":"2026-04-26T02:37:35.486064Z","end":"2026-04-26T02:37:35.617311Z","steps":["trace[72965087] 'process raft request' (duration: 131.055008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.671335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635484757,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T02:37:36.727843Z","caller":"traceutil/trace.go:171","msg":"trace[1605523834] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"653.205231ms","start":"2026-04-26T02:37:36.074595Z","end":"2026-04-26T02:37:36.7278Z","steps":["trace[1605523834] 'process raft request' (duration: 652.971109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.728011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.074572Z","time spent":"653.356633ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.745162Z","caller":"traceutil/trace.go:171","msg":"trace[2092958556] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:522; }","duration":"574.316103ms","start":"2026-04-26T02:37:36.170798Z","end":"2026-04-26T02:37:36.745114Z","steps":["trace[2092958556] 'read index received' (duration: 556.774854ms)","trace[2092958556] 'applied index is now lower than readState.Index' (duration: 17.540039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:37:36.745387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.560075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:37:36.745435Z","caller":"traceutil/trace.go:171","msg":"trace[885110338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"574.636376ms","start":"2026-04-26T02:37:36.170788Z","end":"2026-04-26T02:37:36.745424Z","steps":["trace[885110338] 'agreement among raft nodes before linearized reading' (duration: 574.474024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.745482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.170732Z","time spent":"574.740927ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-26T02:37:36.745679Z","caller":"traceutil/trace.go:171","msg":"trace[164829943] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"651.976757ms","start":"2026-04-26T02:37:36.093682Z","end":"2026-04-26T02:37:36.745658Z","steps":["trace[164829943] 'process raft request' (duration: 651.081458ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:37:36.745729Z","caller":"traceutil/trace.go:171","msg":"trace[399345119] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"649.283569ms","start":"2026-04-26T02:37:36.096431Z","end":"2026-04-26T02:37:36.745714Z","steps":["trace[399345119] 'process raft request' (duration: 648.38695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.745787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.093675Z","time spent":"652.057368ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T02:37:36.745828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.096404Z","time spent":"649.36089ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.745938Z","caller":"traceutil/trace.go:171","msg":"trace[205418422] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"641.683927ms","start":"2026-04-26T02:37:36.104245Z","end":"2026-04-26T02:37:36.745929Z","steps":["trace[205418422] 'process raft request' (duration: 640.623766ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:37:36.745966Z","caller":"traceutil/trace.go:171","msg":"trace[1332731893] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"671.052473ms","start":"2026-04-26T02:37:36.074905Z","end":"2026-04-26T02:37:36.745957Z","steps":["trace[1332731893] 'process raft request' (duration: 669.530257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.74599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.104226Z","time spent":"641.740567ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.746025Z","caller":"traceutil/trace.go:171","msg":"trace[1046468056] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"663.910316ms","start":"2026-04-26T02:37:36.082108Z","end":"2026-04-26T02:37:36.746019Z","steps":["trace[1046468056] 'process raft request' (duration: 662.45824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.082091Z","time spent":"663.950776ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.7461Z","caller":"traceutil/trace.go:171","msg":"trace[412405887] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"662.058787ms","start":"2026-04-26T02:37:36.084036Z","end":"2026-04-26T02:37:36.746095Z","steps":["trace[412405887] 'process raft request' (duration: 660.58614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.084027Z","time spent":"662.084687ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.746158Z","caller":"traceutil/trace.go:171","msg":"trace[2061470706] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"641.218422ms","start":"2026-04-26T02:37:36.104929Z","end":"2026-04-26T02:37:36.746148Z","steps":["trace[2061470706] 'process raft request' (duration: 639.997329ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:37:36.746196Z","caller":"traceutil/trace.go:171","msg":"trace[446565270] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"652.797418ms","start":"2026-04-26T02:37:36.093391Z","end":"2026-04-26T02:37:36.746189Z","steps":["trace[446565270] 'process raft request' (duration: 651.281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.093381Z","time spent":"652.830598ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T02:37:36.746218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.104905Z","time spent":"641.291744ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.746289Z","caller":"traceutil/trace.go:171","msg":"trace[921161764] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"652.832479ms","start":"2026-04-26T02:37:36.093451Z","end":"2026-04-26T02:37:36.746284Z","steps":["trace[921161764] 'process raft request' (duration: 651.263692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.093444Z","time spent":"652.857929ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.746338Z","caller":"traceutil/trace.go:171","msg":"trace[1751624954] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"619.761132ms","start":"2026-04-26T02:37:36.126568Z","end":"2026-04-26T02:37:36.746329Z","steps":["trace[1751624954] 'process raft request' (duration: 618.416338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.074883Z","time spent":"671.098974ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T02:37:36.746513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.126518Z","time spent":"619.918264ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:36.7466Z","caller":"traceutil/trace.go:171","msg":"trace[1208162860] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"482.927781ms","start":"2026-04-26T02:37:36.263643Z","end":"2026-04-26T02:37:36.74657Z","steps":["trace[1208162860] 'process raft request' (duration: 481.415495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:36.746695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:36.263607Z","time spent":"483.040702ms","remote":"127.0.0.1:37012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:39.475005Z","caller":"traceutil/trace.go:171","msg":"trace[850519387] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"338.708191ms","start":"2026-04-26T02:37:39.136274Z","end":"2026-04-26T02:37:39.474983Z","steps":["trace[850519387] 'read index received' (duration: 338.516088ms)","trace[850519387] 'applied index is now lower than readState.Index' (duration: 191.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:37:39.475097Z","caller":"traceutil/trace.go:171","msg":"trace[1705438693] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"374.835269ms","start":"2026-04-26T02:37:39.100197Z","end":"2026-04-26T02:37:39.475033Z","steps":["trace[1705438693] 'process raft request' (duration: 374.654767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:39.475225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.923483ms","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-26T02:37:39.475259Z","caller":"traceutil/trace.go:171","msg":"trace[39428853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"339.004844ms","start":"2026-04-26T02:37:39.136247Z","end":"2026-04-26T02:37:39.475252Z","steps":["trace[39428853] 'agreement among raft nodes before linearized reading' (duration: 338.861572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:39.475288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:39.136236Z","time spent":"339.045554ms","remote":"127.0.0.1:37212","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-26T02:37:39.475342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:39.100184Z","time spent":"375.022891ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:37:39.47538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.835266ms","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-26T02:37:39.475491Z","caller":"traceutil/trace.go:171","msg":"trace[1225290649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"223.010647ms","start":"2026-04-26T02:37:39.252445Z","end":"2026-04-26T02:37:39.475456Z","steps":["trace[1225290649] 'agreement among raft nodes before linearized reading' (duration: 222.709784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:39.798833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.189677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T02:37:39.798966Z","caller":"traceutil/trace.go:171","msg":"trace[1397207844] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"318.574705ms","start":"2026-04-26T02:37:39.480368Z","end":"2026-04-26T02:37:39.798943Z","steps":["trace[1397207844] 'process raft request' (duration: 204.951404ms)","trace[1397207844] 'compare' (duration: 112.999584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:37:39.799052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:39.480346Z","time spent":"318.676166ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:37:39.845321Z","caller":"traceutil/trace.go:171","msg":"trace[1013395010] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"205.522839ms","start":"2026-04-26T02:37:39.639767Z","end":"2026-04-26T02:37:39.84529Z","steps":["trace[1013395010] 'read index received' (duration: 45.56491ms)","trace[1013395010] 'applied index is now lower than readState.Index' (duration: 159.956759ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:37:39.845525Z","caller":"traceutil/trace.go:171","msg":"trace[1416402089] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"364.321736ms","start":"2026-04-26T02:37:39.481176Z","end":"2026-04-26T02:37:39.845498Z","steps":["trace[1416402089] 'process raft request' (duration: 363.973413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:39.845592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.808242ms","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-26T02:37:39.845619Z","caller":"traceutil/trace.go:171","msg":"trace[470040035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"205.861252ms","start":"2026-04-26T02:37:39.63975Z","end":"2026-04-26T02:37:39.845611Z","steps":["trace[470040035] 'agreement among raft nodes before linearized reading' (duration: 205.6391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:39.845672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:39.481155Z","time spent":"364.435337ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:37:40.126742Z","caller":"traceutil/trace.go:171","msg":"trace[371907262] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"165.119275ms","start":"2026-04-26T02:37:39.961581Z","end":"2026-04-26T02:37:40.1267Z","steps":["trace[371907262] 'process raft request' (duration: 122.78506ms)","trace[371907262] 'compare' (duration: 42.141633ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:37:42.968539Z","caller":"traceutil/trace.go:171","msg":"trace[1488707244] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"417.374137ms","start":"2026-04-26T02:37:42.551133Z","end":"2026-04-26T02:37:42.968507Z","steps":["trace[1488707244] 'process raft request' (duration: 415.510836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:42.968741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:42.551098Z","time spent":"417.541608ms","remote":"127.0.0.1:37050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:37:44.53216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.813241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T02:37:44.53229Z","caller":"traceutil/trace.go:171","msg":"trace[540931426] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"364.417398ms","start":"2026-04-26T02:37:44.167853Z","end":"2026-04-26T02:37:44.53227Z","steps":["trace[540931426] 'read index received' (duration: 145.287152ms)","trace[540931426] 'applied index is now lower than readState.Index' (duration: 219.129046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:37:44.532389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.529749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:37:44.532425Z","caller":"traceutil/trace.go:171","msg":"trace[1251592711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"364.62872ms","start":"2026-04-26T02:37:44.167784Z","end":"2026-04-26T02:37:44.532413Z","steps":["trace[1251592711] 'agreement among raft nodes before linearized reading' (duration: 364.536159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:44.532461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:44.167758Z","time spent":"364.696131ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-26T02:37:44.532383Z","caller":"traceutil/trace.go:171","msg":"trace[1597987214] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"525.653692ms","start":"2026-04-26T02:37:44.006644Z","end":"2026-04-26T02:37:44.532298Z","steps":["trace[1597987214] 'process raft request' (duration: 306.614707ms)","trace[1597987214] 'compare' (duration: 218.629259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:37:44.532752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:44.006609Z","time spent":"525.997845ms","remote":"127.0.0.1:37426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:37:44.533379Z","caller":"traceutil/trace.go:171","msg":"trace[468512466] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"159.14515ms","start":"2026-04-26T02:37:44.374216Z","end":"2026-04-26T02:37:44.533362Z","steps":["trace[468512466] 'process raft request' (duration: 158.974379ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:37:58.064853Z","caller":"traceutil/trace.go:171","msg":"trace[546748284] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"126.099146ms","start":"2026-04-26T02:37:57.938719Z","end":"2026-04-26T02:37:58.064818Z","steps":["trace[546748284] 'read index received' (duration: 125.758482ms)","trace[546748284] 'applied index is now lower than readState.Index' (duration: 339.074µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:37:58.064906Z","caller":"traceutil/trace.go:171","msg":"trace[1257170001] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"159.838028ms","start":"2026-04-26T02:37:57.905015Z","end":"2026-04-26T02:37:58.064853Z","steps":["trace[1257170001] 'process raft request' (duration: 159.560615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:58.065204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.441259ms","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-26T02:37:58.065304Z","caller":"traceutil/trace.go:171","msg":"trace[961274378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"126.59774ms","start":"2026-04-26T02:37:57.938683Z","end":"2026-04-26T02:37:58.06528Z","steps":["trace[961274378] 'agreement among raft nodes before linearized reading' (duration: 126.248427ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:37:59.658221Z","caller":"traceutil/trace.go:171","msg":"trace[353830263] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"335.557608ms","start":"2026-04-26T02:37:59.322635Z","end":"2026-04-26T02:37:59.658192Z","steps":["trace[353830263] 'process raft request' (duration: 335.293825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:37:59.65853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:37:59.322579Z","time spent":"335.809371ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:38:01.005318Z","caller":"traceutil/trace.go:171","msg":"trace[2110517570] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"120.163292ms","start":"2026-04-26T02:38:00.885122Z","end":"2026-04-26T02:38:01.005285Z","steps":["trace[2110517570] 'process raft request' (duration: 119.9817ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:39:18.1966Z","caller":"traceutil/trace.go:171","msg":"trace[251608667] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"101.131416ms","start":"2026-04-26T02:39:18.095417Z","end":"2026-04-26T02:39:18.196549Z","steps":["trace[251608667] 'process raft request' (duration: 100.881301ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:39:18.633583Z","caller":"traceutil/trace.go:171","msg":"trace[395495812] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"121.529638ms","start":"2026-04-26T02:39:18.512021Z","end":"2026-04-26T02:39:18.633551Z","steps":["trace[395495812] 'read index received' (duration: 121.269422ms)","trace[395495812] 'applied index is now lower than readState.Index' (duration: 259.286µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:39:18.63374Z","caller":"traceutil/trace.go:171","msg":"trace[1784485620] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"123.840588ms","start":"2026-04-26T02:39:18.509887Z","end":"2026-04-26T02:39:18.633728Z","steps":["trace[1784485620] 'process raft request' (duration: 123.5007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:39:18.634032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.995697ms","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-26T02:39:18.634073Z","caller":"traceutil/trace.go:171","msg":"trace[566786709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"122.078679ms","start":"2026-04-26T02:39:18.511984Z","end":"2026-04-26T02:39:18.634063Z","steps":["trace[566786709] 'agreement among raft nodes before linearized reading' (duration: 121.923746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:39:18.634417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.575736ms","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-26T02:39:18.634531Z","caller":"traceutil/trace.go:171","msg":"trace[949334028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1138; }","duration":"119.77877ms","start":"2026-04-26T02:39:18.514723Z","end":"2026-04-26T02:39:18.634502Z","steps":["trace[949334028] 'agreement among raft nodes before linearized reading' (duration: 119.472694ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:39:48.961606Z","caller":"traceutil/trace.go:171","msg":"trace[228897496] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"150.721691ms","start":"2026-04-26T02:39:48.810851Z","end":"2026-04-26T02:39:48.961573Z","steps":["trace[228897496] 'process raft request' (duration: 150.494337ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:39:48.978715Z","caller":"traceutil/trace.go:171","msg":"trace[1360046034] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"167.656663ms","start":"2026-04-26T02:39:48.811026Z","end":"2026-04-26T02:39:48.978683Z","steps":["trace[1360046034] 'process raft request' (duration: 167.499339ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:39:50.023938Z","caller":"traceutil/trace.go:171","msg":"trace[456889917] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"216.950846ms","start":"2026-04-26T02:39:49.806954Z","end":"2026-04-26T02:39:50.023905Z","steps":["trace[456889917] 'process raft request' (duration: 216.776542ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:40:39.209448Z","caller":"traceutil/trace.go:171","msg":"trace[1410194826] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"131.566706ms","start":"2026-04-26T02:40:39.077838Z","end":"2026-04-26T02:40:39.209405Z","steps":["trace[1410194826] 'process raft request' (duration: 131.360172ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:40:39.45882Z","caller":"traceutil/trace.go:171","msg":"trace[936468151] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"216.761342ms","start":"2026-04-26T02:40:39.242019Z","end":"2026-04-26T02:40:39.45878Z","steps":["trace[936468151] 'process raft request' (duration: 133.388361ms)","trace[936468151] 'compare' (duration: 83.202927ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:40:39.519069Z","caller":"traceutil/trace.go:171","msg":"trace[2071762362] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"276.545834ms","start":"2026-04-26T02:40:39.242478Z","end":"2026-04-26T02:40:39.519023Z","steps":["trace[2071762362] 'process raft request' (duration: 276.383341ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:40:40.350306Z","caller":"traceutil/trace.go:171","msg":"trace[112323439] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"112.800279ms","start":"2026-04-26T02:40:40.23746Z","end":"2026-04-26T02:40:40.35026Z","steps":["trace[112323439] 'process raft request' (duration: 112.479264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:40:43.753402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.625338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:40:43.753502Z","caller":"traceutil/trace.go:171","msg":"trace[414973507] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1417; }","duration":"111.813081ms","start":"2026-04-26T02:40:43.641667Z","end":"2026-04-26T02:40:43.75348Z","steps":["trace[414973507] 'count revisions from in-memory index tree' (duration: 111.455174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:17.402662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.228872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:41:17.422986Z","caller":"traceutil/trace.go:171","msg":"trace[160550748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1526; }","duration":"255.620869ms","start":"2026-04-26T02:41:17.167334Z","end":"2026-04-26T02:41:17.422954Z","steps":["trace[160550748] 'range keys from in-memory index tree' (duration: 235.080888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:17.423377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.386764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T02:41:17.42346Z","caller":"traceutil/trace.go:171","msg":"trace[518741143] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1526; }","duration":"168.547667ms","start":"2026-04-26T02:41:17.254898Z","end":"2026-04-26T02:41:17.423446Z","steps":["trace[518741143] 'count revisions from in-memory index tree' (duration: 168.249911ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:41:25.590761Z","caller":"traceutil/trace.go:171","msg":"trace[525861626] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"105.213297ms","start":"2026-04-26T02:41:25.485516Z","end":"2026-04-26T02:41:25.590729Z","steps":["trace[525861626] 'process raft request' (duration: 104.982982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:26.316686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487263,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T02:41:26.362515Z","caller":"traceutil/trace.go:171","msg":"trace[1751758571] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"546.364853ms","start":"2026-04-26T02:41:25.816127Z","end":"2026-04-26T02:41:26.362492Z","steps":["trace[1751758571] 'read index received' (duration: 546.21163ms)","trace[1751758571] 'applied index is now lower than readState.Index' (duration: 152.233µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:41:26.362692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.813478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:41:26.362727Z","caller":"traceutil/trace.go:171","msg":"trace[1512070820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1554; }","duration":"191.861459ms","start":"2026-04-26T02:41:26.170856Z","end":"2026-04-26T02:41:26.362717Z","steps":["trace[1512070820] 'agreement among raft nodes before linearized reading' (duration: 191.795558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:26.362736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.793035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T02:41:26.362781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.626907ms","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-26T02:41:26.362851Z","caller":"traceutil/trace.go:171","msg":"trace[30560282] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1554; }","duration":"320.957177ms","start":"2026-04-26T02:41:26.041864Z","end":"2026-04-26T02:41:26.362821Z","steps":["trace[30560282] 'agreement among raft nodes before linearized reading' (duration: 320.790594ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:41:26.362889Z","caller":"traceutil/trace.go:171","msg":"trace[64228483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1554; }","duration":"546.794312ms","start":"2026-04-26T02:41:25.816071Z","end":"2026-04-26T02:41:26.362865Z","steps":["trace[64228483] 'agreement among raft nodes before linearized reading' (duration: 546.500766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:26.36292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:26.041839Z","time spent":"321.067429ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:41:26.362945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:25.816044Z","time spent":"546.892073ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:41:26.363459Z","caller":"traceutil/trace.go:171","msg":"trace[1867990070] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"623.619587ms","start":"2026-04-26T02:41:25.739789Z","end":"2026-04-26T02:41:26.363408Z","steps":["trace[1867990070] 'process raft request' (duration: 622.545118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:26.363682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:25.739762Z","time spent":"623.806392ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:41:27.312639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T02:41:27.813706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T02:41:28.255331Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.887825347s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T02:41:28.255937Z","caller":"traceutil/trace.go:171","msg":"trace[1585262453] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"1.88850308s","start":"2026-04-26T02:41:26.367403Z","end":"2026-04-26T02:41:28.255907Z","steps":["trace[1585262453] 'process raft request' (duration: 1.888300846s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.256166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:26.36738Z","time spent":"1.888651862s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:41:28.25982Z","caller":"traceutil/trace.go:171","msg":"trace[269224735] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"1.796296721s","start":"2026-04-26T02:41:26.463493Z","end":"2026-04-26T02:41:28.25979Z","steps":["trace[269224735] 'process raft request' (duration: 1.796008565s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.259979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:26.46346Z","time spent":"1.796441243s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:41:28.260087Z","caller":"traceutil/trace.go:171","msg":"trace[591708186] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"1.623307969s","start":"2026-04-26T02:41:26.636755Z","end":"2026-04-26T02:41:28.260062Z","steps":["trace[591708186] 'process raft request' (duration: 1.622945242s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.260192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:26.636726Z","time spent":"1.623417781s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:41:28.261654Z","caller":"traceutil/trace.go:171","msg":"trace[342525812] linearizableReadLoop","detail":"{readStateIndex:1625; appliedIndex:1621; }","duration":"1.449585044s","start":"2026-04-26T02:41:26.812033Z","end":"2026-04-26T02:41:28.261618Z","steps":["trace[342525812] 'read index received' (duration: 1.443590771s)","trace[342525812] 'applied index is now lower than readState.Index' (duration: 5.992583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:41:28.261979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44990608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T02:41:28.262008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21319948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-26T02:41:28.262035Z","caller":"traceutil/trace.go:171","msg":"trace[2062935791] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1557; }","duration":"1.450049242s","start":"2026-04-26T02:41:26.811972Z","end":"2026-04-26T02:41:28.262021Z","steps":["trace[2062935791] 'agreement among raft nodes before linearized reading' (duration: 1.449864469s)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:41:28.262066Z","caller":"traceutil/trace.go:171","msg":"trace[504321773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.213305272s","start":"2026-04-26T02:41:27.048748Z","end":"2026-04-26T02:41:28.262053Z","steps":["trace[504321773] 'agreement among raft nodes before linearized reading' (duration: 1.213121719s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.2621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:26.811951Z","time spent":"1.450136464s","remote":"127.0.0.1:37302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:41:28.262122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:27.048726Z","time spent":"1.213387363s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:41:28.262364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.96416ms","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-26T02:41:28.26242Z","caller":"traceutil/trace.go:171","msg":"trace[1492345914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1557; }","duration":"667.061182ms","start":"2026-04-26T02:41:27.595352Z","end":"2026-04-26T02:41:28.262413Z","steps":["trace[1492345914] 'agreement among raft nodes before linearized reading' (duration: 666.953821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.262443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:27.595332Z","time spent":"667.105433ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T02:41:28.262524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.741147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:41:28.262559Z","caller":"traceutil/trace.go:171","msg":"trace[1752856314] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1557; }","duration":"675.814838ms","start":"2026-04-26T02:41:27.586738Z","end":"2026-04-26T02:41:28.262553Z","steps":["trace[1752856314] 'agreement among raft nodes before linearized reading' (duration: 675.760397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.262578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:27.586717Z","time spent":"675.855689ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:41:28.262671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148095716s","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-26T02:41:28.262754Z","caller":"traceutil/trace.go:171","msg":"trace[972928999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.148216508s","start":"2026-04-26T02:41:27.114519Z","end":"2026-04-26T02:41:28.262735Z","steps":["trace[972928999] 'agreement among raft nodes before linearized reading' (duration: 1.147991104s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.262814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:27.114501Z","time spent":"1.1483009s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T02:41:28.262834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.463557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:41:28.262874Z","caller":"traceutil/trace.go:171","msg":"trace[103750857] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1557; }","duration":"931.589639ms","start":"2026-04-26T02:41:27.331272Z","end":"2026-04-26T02:41:28.262862Z","steps":["trace[103750857] 'agreement among raft nodes before linearized reading' (duration: 931.524438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:28.262912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:27.331251Z","time spent":"931.65279ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-26T02:41:52.826595Z","caller":"traceutil/trace.go:171","msg":"trace[636301351] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"570.390725ms","start":"2026-04-26T02:41:52.256152Z","end":"2026-04-26T02:41:52.826543Z","steps":["trace[636301351] 'process raft request' (duration: 542.006225ms)","trace[636301351] 'compare' (duration: 27.722507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:41:52.826805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.256122Z","time spent":"570.561759ms","remote":"127.0.0.1:36992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:41:52.869099Z","caller":"traceutil/trace.go:171","msg":"trace[1863404429] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"523.937411ms","start":"2026-04-26T02:41:52.345142Z","end":"2026-04-26T02:41:52.869079Z","steps":["trace[1863404429] 'process raft request' (duration: 523.705957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.869369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.345116Z","time spent":"524.135004ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:41:52.869357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.54549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-26T02:41:52.869455Z","caller":"traceutil/trace.go:171","msg":"trace[202848830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1636; }","duration":"506.716873ms","start":"2026-04-26T02:41:52.362717Z","end":"2026-04-26T02:41:52.869434Z","steps":["trace[202848830] 'agreement among raft nodes before linearized reading' (duration: 506.429257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.869506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.362691Z","time spent":"506.807526ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:41:52.869646Z","caller":"traceutil/trace.go:171","msg":"trace[388807912] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1707; }","duration":"506.241074ms","start":"2026-04-26T02:41:52.362785Z","end":"2026-04-26T02:41:52.869026Z","steps":["trace[388807912] 'read index received' (duration: 435.288526ms)","trace[388807912] 'applied index is now lower than readState.Index' (duration: 70.950738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:41:52.870457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.3661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-26T02:41:52.870513Z","caller":"traceutil/trace.go:171","msg":"trace[519569542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1636; }","duration":"492.444211ms","start":"2026-04-26T02:41:52.378056Z","end":"2026-04-26T02:41:52.8705Z","steps":["trace[519569542] 'agreement among raft nodes before linearized reading' (duration: 492.187537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.870784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.305203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-26T02:41:52.870837Z","caller":"traceutil/trace.go:171","msg":"trace[509628525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1636; }","duration":"488.375384ms","start":"2026-04-26T02:41:52.382447Z","end":"2026-04-26T02:41:52.870822Z","steps":["trace[509628525] 'agreement among raft nodes before linearized reading' (duration: 488.17479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.870869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.382437Z","time spent":"488.425085ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T02:41:52.870851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.378038Z","time spent":"492.797198ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T02:41:52.87088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.679238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-26T02:41:52.870924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.402179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T02:41:52.871095Z","caller":"traceutil/trace.go:171","msg":"trace[1064648129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1636; }","duration":"384.585153ms","start":"2026-04-26T02:41:52.486486Z","end":"2026-04-26T02:41:52.871071Z","steps":["trace[1064648129] 'agreement among raft nodes before linearized reading' (duration: 384.038352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.871161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.486436Z","time spent":"384.712404ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-26T02:41:52.871685Z","caller":"traceutil/trace.go:171","msg":"trace[710039186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1636; }","duration":"470.884253ms","start":"2026-04-26T02:41:52.400096Z","end":"2026-04-26T02:41:52.87098Z","steps":["trace[710039186] 'agreement among raft nodes before linearized reading' (duration: 470.479135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:52.871796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:52.400071Z","time spent":"471.678638ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-26T02:41:55.097575Z","caller":"traceutil/trace.go:171","msg":"trace[860919837] linearizableReadLoop","detail":"{readStateIndex:1715; appliedIndex:1714; }","duration":"204.897956ms","start":"2026-04-26T02:41:54.892628Z","end":"2026-04-26T02:41:55.097526Z","steps":["trace[860919837] 'read index received' (duration: 204.359755ms)","trace[860919837] 'applied index is now lower than readState.Index' (duration: 537.161µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:41:55.097751Z","caller":"traceutil/trace.go:171","msg":"trace[1991336212] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"222.251866ms","start":"2026-04-26T02:41:54.875476Z","end":"2026-04-26T02:41:55.097728Z","steps":["trace[1991336212] 'process raft request' (duration: 221.618844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:55.097932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.272964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-26T02:41:55.097976Z","caller":"traceutil/trace.go:171","msg":"trace[1836979145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1642; }","duration":"205.382825ms","start":"2026-04-26T02:41:54.89258Z","end":"2026-04-26T02:41:55.097963Z","steps":["trace[1836979145] 'agreement among raft nodes before linearized reading' (duration: 205.171261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:55.098198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.331666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-26T02:41:55.098227Z","caller":"traceutil/trace.go:171","msg":"trace[182199099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1642; }","duration":"172.380158ms","start":"2026-04-26T02:41:54.925838Z","end":"2026-04-26T02:41:55.098218Z","steps":["trace[182199099] 'agreement among raft nodes before linearized reading' (duration: 172.297236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:55.098299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.084362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-26T02:41:55.098399Z","caller":"traceutil/trace.go:171","msg":"trace[593486995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1642; }","duration":"171.219996ms","start":"2026-04-26T02:41:54.927158Z","end":"2026-04-26T02:41:55.098378Z","steps":["trace[593486995] 'agreement among raft nodes before linearized reading' (duration: 170.949791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:55.098404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.592161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-26T02:41:55.09844Z","caller":"traceutil/trace.go:171","msg":"trace[1042420663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1642; }","duration":"172.653643ms","start":"2026-04-26T02:41:54.925776Z","end":"2026-04-26T02:41:55.09843Z","steps":["trace[1042420663] 'agreement among raft nodes before linearized reading' (duration: 172.542261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:55.098736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.615131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T02:41:55.098772Z","caller":"traceutil/trace.go:171","msg":"trace[71675949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1642; }","duration":"173.676052ms","start":"2026-04-26T02:41:54.925086Z","end":"2026-04-26T02:41:55.098762Z","steps":["trace[71675949] 'agreement among raft nodes before linearized reading' (duration: 173.004028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.623997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487500,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T02:41:57.736821Z","caller":"traceutil/trace.go:171","msg":"trace[1783604531] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"632.135119ms","start":"2026-04-26T02:41:57.104653Z","end":"2026-04-26T02:41:57.736788Z","steps":["trace[1783604531] 'process raft request' (duration: 631.960866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.737008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.104632Z","time spent":"632.279181ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:41:57.94932Z","caller":"traceutil/trace.go:171","msg":"trace[1062520799] linearizableReadLoop","detail":"{readStateIndex:1722; appliedIndex:1720; }","duration":"826.115696ms","start":"2026-04-26T02:41:57.123167Z","end":"2026-04-26T02:41:57.949283Z","steps":["trace[1062520799] 'read index received' (duration: 613.457533ms)","trace[1062520799] 'applied index is now lower than readState.Index' (duration: 212.656313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:41:57.949781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.581945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-26T02:41:57.949812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.121752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T02:41:57.949883Z","caller":"traceutil/trace.go:171","msg":"trace[379464955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1648; }","duration":"826.704287ms","start":"2026-04-26T02:41:57.123155Z","end":"2026-04-26T02:41:57.949859Z","steps":["trace[379464955] 'agreement among raft nodes before linearized reading' (duration: 826.383531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.949935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.500849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-26T02:41:57.950002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.123089Z","time spent":"826.844539ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-26T02:41:57.950023Z","caller":"traceutil/trace.go:171","msg":"trace[572180596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1648; }","duration":"749.598172ms","start":"2026-04-26T02:41:57.20041Z","end":"2026-04-26T02:41:57.950008Z","steps":["trace[572180596] 'agreement among raft nodes before linearized reading' (duration: 749.466749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.950073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.391977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-26T02:41:57.950123Z","caller":"traceutil/trace.go:171","msg":"trace[1492209317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1648; }","duration":"751.489288ms","start":"2026-04-26T02:41:57.198623Z","end":"2026-04-26T02:41:57.950113Z","steps":["trace[1492209317] 'agreement among raft nodes before linearized reading' (duration: 751.376446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.950166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.198598Z","time spent":"751.559879ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T02:41:57.949912Z","caller":"traceutil/trace.go:171","msg":"trace[478154266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1648; }","duration":"751.268694ms","start":"2026-04-26T02:41:57.198615Z","end":"2026-04-26T02:41:57.949883Z","steps":["trace[478154266] 'agreement among raft nodes before linearized reading' (duration: 751.01324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.950277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.198579Z","time spent":"751.687141ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T02:41:57.950077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.200402Z","time spent":"749.664883ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:41:57.949847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.0899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-26T02:41:57.950633Z","caller":"traceutil/trace.go:171","msg":"trace[1635025559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1648; }","duration":"751.917546ms","start":"2026-04-26T02:41:57.198704Z","end":"2026-04-26T02:41:57.950622Z","steps":["trace[1635025559] 'agreement among raft nodes before linearized reading' (duration: 750.947637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:41:57.950669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:41:57.198694Z","time spent":"751.961937ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:42:10.120507Z","caller":"traceutil/trace.go:171","msg":"trace[1456218733] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"340.706047ms","start":"2026-04-26T02:42:09.779755Z","end":"2026-04-26T02:42:10.120461Z","steps":["trace[1456218733] 'process raft request' (duration: 340.31729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:10.120733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:09.77973Z","time spent":"340.888202ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:12.635534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487712,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T02:42:12.729995Z","caller":"traceutil/trace.go:171","msg":"trace[2026218694] linearizableReadLoop","detail":"{readStateIndex:1825; appliedIndex:1824; }","duration":"595.151055ms","start":"2026-04-26T02:42:12.134783Z","end":"2026-04-26T02:42:12.729934Z","steps":["trace[2026218694] 'read index received' (duration: 594.87386ms)","trace[2026218694] 'applied index is now lower than readState.Index' (duration: 276.405µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:12.730037Z","caller":"traceutil/trace.go:171","msg":"trace[1033598956] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"595.331548ms","start":"2026-04-26T02:42:12.134663Z","end":"2026-04-26T02:42:12.729994Z","steps":["trace[1033598956] 'process raft request' (duration: 594.926121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.44047ms","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-26T02:42:12.730303Z","caller":"traceutil/trace.go:171","msg":"trace[675259580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1747; }","duration":"595.584343ms","start":"2026-04-26T02:42:12.134705Z","end":"2026-04-26T02:42:12.730289Z","steps":["trace[675259580] 'agreement among raft nodes before linearized reading' (duration: 595.358669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.673797ms","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-26T02:42:12.730326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.369419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T02:42:12.73034Z","caller":"traceutil/trace.go:171","msg":"trace[645130427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1747; }","duration":"593.717887ms","start":"2026-04-26T02:42:12.136611Z","end":"2026-04-26T02:42:12.730328Z","steps":["trace[645130427] 'agreement among raft nodes before linearized reading' (duration: 593.634366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.134659Z","time spent":"595.669814ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:12.730388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.136605Z","time spent":"593.777579ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:42:12.730406Z","caller":"traceutil/trace.go:171","msg":"trace[1965305670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1747; }","duration":"594.495182ms","start":"2026-04-26T02:42:12.13586Z","end":"2026-04-26T02:42:12.730356Z","steps":["trace[1965305670] 'agreement among raft nodes before linearized reading' (duration: 594.287778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.73034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.611514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-26T02:42:12.730457Z","caller":"traceutil/trace.go:171","msg":"trace[1033214269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1747; }","duration":"593.736938ms","start":"2026-04-26T02:42:12.136708Z","end":"2026-04-26T02:42:12.730444Z","steps":["trace[1033214269] 'agreement among raft nodes before linearized reading' (duration: 593.526684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.135827Z","time spent":"594.624206ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:12.730492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.136703Z","time spent":"593.778339ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:12.730503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.095247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T02:42:12.730542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.148129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-26T02:42:12.730539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.483693ms","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-26T02:42:12.730571Z","caller":"traceutil/trace.go:171","msg":"trace[1478899548] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1747; }","duration":"177.213259ms","start":"2026-04-26T02:42:12.553331Z","end":"2026-04-26T02:42:12.730544Z","steps":["trace[1478899548] 'agreement among raft nodes before linearized reading' (duration: 177.131848ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.730578Z","caller":"traceutil/trace.go:171","msg":"trace[2023114303] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1747; }","duration":"550.207371ms","start":"2026-04-26T02:42:12.18036Z","end":"2026-04-26T02:42:12.730568Z","steps":["trace[2023114303] 'agreement among raft nodes before linearized reading' (duration: 550.112709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.963964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:12.730622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.180346Z","time spent":"550.267392ms","remote":"127.0.0.1:36992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"info","ts":"2026-04-26T02:42:12.730635Z","caller":"traceutil/trace.go:171","msg":"trace[907145109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1747; }","duration":"562.348272ms","start":"2026-04-26T02:42:12.168273Z","end":"2026-04-26T02:42:12.730621Z","steps":["trace[907145109] 'agreement among raft nodes before linearized reading' (duration: 561.923743ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.730629Z","caller":"traceutil/trace.go:171","msg":"trace[723721344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1747; }","duration":"594.589764ms","start":"2026-04-26T02:42:12.136015Z","end":"2026-04-26T02:42:12.730605Z","steps":["trace[723721344] 'agreement among raft nodes before linearized reading' (duration: 594.255287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.168249Z","time spent":"562.409912ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T02:42:12.730545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.234648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T02:42:12.730689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.136011Z","time spent":"594.669226ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-26T02:42:12.730717Z","caller":"traceutil/trace.go:171","msg":"trace[704539389] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1747; }","duration":"583.437612ms","start":"2026-04-26T02:42:12.147265Z","end":"2026-04-26T02:42:12.730702Z","steps":["trace[704539389] 'agreement among raft nodes before linearized reading' (duration: 583.176077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:12.730755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.147246Z","time spent":"583.498634ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:42:12.7309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:12.134612Z","time spent":"595.515312ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:12.968538Z","caller":"traceutil/trace.go:171","msg":"trace[579861538] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"233.138371ms","start":"2026-04-26T02:42:12.735362Z","end":"2026-04-26T02:42:12.9685Z","steps":["trace[579861538] 'process raft request' (duration: 232.694352ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.968903Z","caller":"traceutil/trace.go:171","msg":"trace[94010772] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"232.850135ms","start":"2026-04-26T02:42:12.736031Z","end":"2026-04-26T02:42:12.968881Z","steps":["trace[94010772] 'process raft request' (duration: 232.810715ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.969033Z","caller":"traceutil/trace.go:171","msg":"trace[602500248] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"233.039129ms","start":"2026-04-26T02:42:12.735986Z","end":"2026-04-26T02:42:12.969025Z","steps":["trace[602500248] 'process raft request' (duration: 232.823645ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.96894Z","caller":"traceutil/trace.go:171","msg":"trace[128487013] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"233.05135ms","start":"2026-04-26T02:42:12.735859Z","end":"2026-04-26T02:42:12.96891Z","steps":["trace[128487013] 'process raft request' (duration: 232.847056ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:12.969103Z","caller":"traceutil/trace.go:171","msg":"trace[389319124] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"233.312265ms","start":"2026-04-26T02:42:12.735781Z","end":"2026-04-26T02:42:12.969093Z","steps":["trace[389319124] 'process raft request' (duration: 232.613222ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:25.240504Z","caller":"traceutil/trace.go:171","msg":"trace[842545246] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1868; }","duration":"189.065064ms","start":"2026-04-26T02:42:25.051403Z","end":"2026-04-26T02:42:25.240468Z","steps":["trace[842545246] 'read index received' (duration: 25.023166ms)","trace[842545246] 'applied index is now lower than readState.Index' (duration: 164.040478ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:25.240673Z","caller":"traceutil/trace.go:171","msg":"trace[759377315] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"197.303061ms","start":"2026-04-26T02:42:25.043352Z","end":"2026-04-26T02:42:25.240655Z","steps":["trace[759377315] 'process raft request' (duration: 181.626382ms)","trace[759377315] 'compare' (duration: 15.169519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:25.240965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.554244ms","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-26T02:42:25.241007Z","caller":"traceutil/trace.go:171","msg":"trace[232341799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1789; }","duration":"189.642346ms","start":"2026-04-26T02:42:25.051353Z","end":"2026-04-26T02:42:25.240996Z","steps":["trace[232341799] 'agreement among raft nodes before linearized reading' (duration: 189.453433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:25.241268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.736988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-26T02:42:25.241425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.827679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-26T02:42:25.241445Z","caller":"traceutil/trace.go:171","msg":"trace[503074700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1789; }","duration":"189.952761ms","start":"2026-04-26T02:42:25.051439Z","end":"2026-04-26T02:42:25.241392Z","steps":["trace[503074700] 'agreement among raft nodes before linearized reading' (duration: 189.429642ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:25.241471Z","caller":"traceutil/trace.go:171","msg":"trace[1493847480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1789; }","duration":"189.88939ms","start":"2026-04-26T02:42:25.051571Z","end":"2026-04-26T02:42:25.24146Z","steps":["trace[1493847480] 'agreement among raft nodes before linearized reading' (duration: 189.780808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.4448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111058276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-26T02:42:28.444902Z","caller":"traceutil/trace.go:171","msg":"trace[25145554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1795; }","duration":"1.111249179s","start":"2026-04-26T02:42:27.333631Z","end":"2026-04-26T02:42:28.44488Z","steps":["trace[25145554] 'range keys from in-memory index tree' (duration: 1.11083357s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.444946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.333602Z","time spent":"1.111334841s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:28.444896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189822392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:28.445045Z","caller":"traceutil/trace.go:171","msg":"trace[1897124215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1795; }","duration":"1.189992286s","start":"2026-04-26T02:42:27.255Z","end":"2026-04-26T02:42:28.444993Z","steps":["trace[1897124215] 'count revisions from in-memory index tree' (duration: 1.189744981s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.445013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197399406s","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-26T02:42:28.445126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.254989Z","time spent":"1.190117348s","remote":"127.0.0.1:32858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-26T02:42:28.445204Z","caller":"traceutil/trace.go:171","msg":"trace[686197146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"1.197670371s","start":"2026-04-26T02:42:27.247499Z","end":"2026-04-26T02:42:28.445169Z","steps":["trace[686197146] 'range keys from in-memory index tree' (duration: 1.197097651s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.445312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.606921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T02:42:28.445329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.247475Z","time spent":"1.197814494s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:42:28.445373Z","caller":"traceutil/trace.go:171","msg":"trace[548573045] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1795; }","duration":"619.728774ms","start":"2026-04-26T02:42:27.825627Z","end":"2026-04-26T02:42:28.445356Z","steps":["trace[548573045] 'count revisions from in-memory index tree' (duration: 619.489969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.445345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13444738s","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-26T02:42:28.445418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.825604Z","time spent":"619.803755ms","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-26T02:42:28.445444Z","caller":"traceutil/trace.go:171","msg":"trace[126384256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"1.134604313s","start":"2026-04-26T02:42:27.310814Z","end":"2026-04-26T02:42:28.445418Z","steps":["trace[126384256] 'range keys from in-memory index tree' (duration: 1.134203665s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.445506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.31079Z","time spent":"1.134706295s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T02:42:28.445449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111735858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T02:42:28.445619Z","caller":"traceutil/trace.go:171","msg":"trace[1018403606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1795; }","duration":"1.111954593s","start":"2026-04-26T02:42:27.333652Z","end":"2026-04-26T02:42:28.445606Z","steps":["trace[1018403606] 'range keys from in-memory index tree' (duration: 1.111500483s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.445657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:27.333629Z","time spent":"1.112016224s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T02:42:28.547634Z","caller":"traceutil/trace.go:171","msg":"trace[115545102] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"379.027566ms","start":"2026-04-26T02:42:28.168567Z","end":"2026-04-26T02:42:28.547595Z","steps":["trace[115545102] 'read index received' (duration: 372.280349ms)","trace[115545102] 'applied index is now lower than readState.Index' (duration: 6.743367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:28.555446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.866516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:28.555523Z","caller":"traceutil/trace.go:171","msg":"trace[1042271104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1797; }","duration":"386.987498ms","start":"2026-04-26T02:42:28.168513Z","end":"2026-04-26T02:42:28.555501Z","steps":["trace[1042271104] 'agreement among raft nodes before linearized reading' (duration: 379.213571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:28.555565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:28.168493Z","time spent":"387.06206ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-26T02:42:28.555787Z","caller":"traceutil/trace.go:171","msg":"trace[1806816921] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"101.666922ms","start":"2026-04-26T02:42:28.454112Z","end":"2026-04-26T02:42:28.555779Z","steps":["trace[1806816921] 'process raft request' (duration: 93.439325ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:28.556036Z","caller":"traceutil/trace.go:171","msg":"trace[1906020730] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"100.648393ms","start":"2026-04-26T02:42:28.455379Z","end":"2026-04-26T02:42:28.556027Z","steps":["trace[1906020730] 'process raft request' (duration: 100.363447ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:30.293353Z","caller":"traceutil/trace.go:171","msg":"trace[1647879269] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"139.251307ms","start":"2026-04-26T02:42:30.154078Z","end":"2026-04-26T02:42:30.293329Z","steps":["trace[1647879269] 'process raft request' (duration: 136.455124ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:30.295613Z","caller":"traceutil/trace.go:171","msg":"trace[632653649] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"141.307356ms","start":"2026-04-26T02:42:30.154279Z","end":"2026-04-26T02:42:30.295586Z","steps":["trace[632653649] 'process raft request' (duration: 141.042021ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:30.295746Z","caller":"traceutil/trace.go:171","msg":"trace[2027520029] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1901; }","duration":"130.668854ms","start":"2026-04-26T02:42:30.165046Z","end":"2026-04-26T02:42:30.295715Z","steps":["trace[2027520029] 'read index received' (duration: 125.561976ms)","trace[2027520029] 'applied index is now lower than readState.Index' (duration: 5.105068ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:30.295885Z","caller":"traceutil/trace.go:171","msg":"trace[144945642] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"140.825617ms","start":"2026-04-26T02:42:30.15505Z","end":"2026-04-26T02:42:30.295875Z","steps":["trace[144945642] 'process raft request' (duration: 140.47937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:30.296262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.191013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:30.2963Z","caller":"traceutil/trace.go:171","msg":"trace[1224363976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"131.267285ms","start":"2026-04-26T02:42:30.165022Z","end":"2026-04-26T02:42:30.29629Z","steps":["trace[1224363976] 'agreement among raft nodes before linearized reading' (duration: 131.178094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.048919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.328137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T02:42:36.04933Z","caller":"traceutil/trace.go:171","msg":"trace[149852129] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"1.01776506s","start":"2026-04-26T02:42:35.03154Z","end":"2026-04-26T02:42:36.049305Z","steps":["trace[149852129] 'process raft request' (duration: 1.017699489s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.049433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:35.031518Z","time spent":"1.017863552s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1768,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:36.049847Z","caller":"traceutil/trace.go:171","msg":"trace[1170389053] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"1.152654855s","start":"2026-04-26T02:42:34.897153Z","end":"2026-04-26T02:42:36.049807Z","steps":["trace[1170389053] 'process raft request' (duration: 1.151998652s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.050029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:34.897089Z","time spent":"1.1528484s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:36.050159Z","caller":"traceutil/trace.go:171","msg":"trace[1590834231] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"1.461905433s","start":"2026-04-26T02:42:34.588201Z","end":"2026-04-26T02:42:36.050107Z","steps":["trace[1590834231] 'process raft request' (duration: 1.460833972s)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:36.050279Z","caller":"traceutil/trace.go:171","msg":"trace[2063275547] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"1.462335491s","start":"2026-04-26T02:42:34.58793Z","end":"2026-04-26T02:42:36.050266Z","steps":["trace[2063275547] 'read index received' (duration: 94.274102ms)","trace[2063275547] 'applied index is now lower than readState.Index' (duration: 1.368059559s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:36.050334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:34.588174Z","time spent":"1.462073746s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:36.050535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462600596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-26T02:42:36.05058Z","caller":"traceutil/trace.go:171","msg":"trace[1959137976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1868; }","duration":"1.462698457s","start":"2026-04-26T02:42:34.587873Z","end":"2026-04-26T02:42:36.050571Z","steps":["trace[1959137976] 'agreement among raft nodes before linearized reading' (duration: 1.462522074s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.050614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:34.587853Z","time spent":"1.462753899s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:36.050674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382079846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T02:42:36.050732Z","caller":"traceutil/trace.go:171","msg":"trace[1699147540] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1868; }","duration":"1.382191788s","start":"2026-04-26T02:42:34.668525Z","end":"2026-04-26T02:42:36.050717Z","steps":["trace[1699147540] 'agreement among raft nodes before linearized reading' (duration: 1.382100416s)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:36.050719Z","caller":"traceutil/trace.go:171","msg":"trace[300050353] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"1.462787459s","start":"2026-04-26T02:42:34.587892Z","end":"2026-04-26T02:42:36.05068Z","steps":["trace[300050353] 'process raft request' (duration: 571.610807ms)","trace[300050353] 'compare' (duration: 888.951021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:36.050796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.735099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1783"} {"level":"info","ts":"2026-04-26T02:42:36.050846Z","caller":"traceutil/trace.go:171","msg":"trace[1160462713] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1868; }","duration":"621.832461ms","start":"2026-04-26T02:42:35.429Z","end":"2026-04-26T02:42:36.050833Z","steps":["trace[1160462713] 'agreement among raft nodes before linearized reading' (duration: 621.715929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.050888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:35.428976Z","time spent":"621.904473ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1807,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-26T02:42:36.0509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.752899ms","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-26T02:42:36.050933Z","caller":"traceutil/trace.go:171","msg":"trace[752064063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"855.839971ms","start":"2026-04-26T02:42:35.195084Z","end":"2026-04-26T02:42:36.050924Z","steps":["trace[752064063] 'agreement among raft nodes before linearized reading' (duration: 855.717439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.050932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:34.58787Z","time spent":"1.462952443s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:36.050968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:35.195054Z","time spent":"855.907301ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:36.050777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:34.668495Z","time spent":"1.382273219s","remote":"127.0.0.1:37100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:42:36.559546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635487977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T02:42:36.848091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.280553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T02:42:36.848222Z","caller":"traceutil/trace.go:171","msg":"trace[1665377077] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"790.785676ms","start":"2026-04-26T02:42:36.057415Z","end":"2026-04-26T02:42:36.848201Z","steps":["trace[1665377077] 'process raft request' (duration: 615.002713ms)","trace[1665377077] 'compare' (duration: 175.056338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:36.848277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.057392Z","time spent":"790.864158ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:42:36.942582Z","caller":"traceutil/trace.go:171","msg":"trace[353607259] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"884.295043ms","start":"2026-04-26T02:42:36.058267Z","end":"2026-04-26T02:42:36.942562Z","steps":["trace[353607259] 'process raft request' (duration: 884.212081ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:36.942586Z","caller":"traceutil/trace.go:171","msg":"trace[899115758] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"884.550168ms","start":"2026-04-26T02:42:36.057999Z","end":"2026-04-26T02:42:36.942549Z","steps":["trace[899115758] 'process raft request' (duration: 884.345134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.942699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.058254Z","time spent":"884.384504ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:36.94279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.057968Z","time spent":"884.707151ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:36.942903Z","caller":"traceutil/trace.go:171","msg":"trace[1911633721] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1951; }","duration":"884.188201ms","start":"2026-04-26T02:42:36.058634Z","end":"2026-04-26T02:42:36.942822Z","steps":["trace[1911633721] 'read index received' (duration: 613.75646ms)","trace[1911633721] 'applied index is now lower than readState.Index' (duration: 270.428561ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:36.942942Z","caller":"traceutil/trace.go:171","msg":"trace[1146726234] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"884.249363ms","start":"2026-04-26T02:42:36.058659Z","end":"2026-04-26T02:42:36.942908Z","steps":["trace[1146726234] 'process raft request' (duration: 883.861475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.943126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.495997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1783"} {"level":"info","ts":"2026-04-26T02:42:36.943242Z","caller":"traceutil/trace.go:171","msg":"trace[1514969351] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1872; }","duration":"884.568839ms","start":"2026-04-26T02:42:36.058592Z","end":"2026-04-26T02:42:36.943161Z","steps":["trace[1514969351] 'agreement among raft nodes before linearized reading' (duration: 884.392586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:36.943289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.058573Z","time spent":"884.705451ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1807,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-26T02:42:36.943239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.058618Z","time spent":"884.493217ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:37.054627Z","caller":"traceutil/trace.go:171","msg":"trace[528500216] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"111.571951ms","start":"2026-04-26T02:42:36.943026Z","end":"2026-04-26T02:42:37.054598Z","steps":["trace[528500216] 'read index received' (duration: 26.447993ms)","trace[528500216] 'applied index is now lower than readState.Index' (duration: 85.122358ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:37.054674Z","caller":"traceutil/trace.go:171","msg":"trace[1286842838] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"137.86176ms","start":"2026-04-26T02:42:36.916762Z","end":"2026-04-26T02:42:37.054624Z","steps":["trace[1286842838] 'process raft request' (duration: 52.699191ms)","trace[1286842838] 'compare' (duration: 84.981896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:37.054897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.424542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T02:42:37.054963Z","caller":"traceutil/trace.go:171","msg":"trace[514020618] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1873; }","duration":"461.563246ms","start":"2026-04-26T02:42:36.593388Z","end":"2026-04-26T02:42:37.054952Z","steps":["trace[514020618] 'agreement among raft nodes before linearized reading' (duration: 461.373172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.055006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.593364Z","time spent":"461.632667ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:42:37.05505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.763561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:37.05512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.441026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-26T02:42:37.05512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.325911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:37.055182Z","caller":"traceutil/trace.go:171","msg":"trace[1906200408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"367.550638ms","start":"2026-04-26T02:42:36.687613Z","end":"2026-04-26T02:42:37.055164Z","steps":["trace[1906200408] 'agreement among raft nodes before linearized reading' (duration: 367.345414ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:37.055228Z","caller":"traceutil/trace.go:171","msg":"trace[1249537045] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1873; }","duration":"259.546205ms","start":"2026-04-26T02:42:36.795668Z","end":"2026-04-26T02:42:37.055214Z","steps":["trace[1249537045] 'agreement among raft nodes before linearized reading' (duration: 259.168707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.055238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.687591Z","time spent":"367.633079ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T02:42:37.055156Z","caller":"traceutil/trace.go:171","msg":"trace[892736746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"885.918104ms","start":"2026-04-26T02:42:36.169216Z","end":"2026-04-26T02:42:37.055134Z","steps":["trace[892736746] 'agreement among raft nodes before linearized reading' (duration: 885.707081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.055333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.169203Z","time spent":"886.120408ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T02:42:37.05536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.167415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-26T02:42:37.05553Z","caller":"traceutil/trace.go:171","msg":"trace[1672494972] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1873; }","duration":"950.40764ms","start":"2026-04-26T02:42:36.1051Z","end":"2026-04-26T02:42:37.055508Z","steps":["trace[1672494972] 'agreement among raft nodes before linearized reading' (duration: 949.931252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.055615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.105086Z","time spent":"950.516772ms","remote":"127.0.0.1:37100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1267,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-04-26T02:42:37.055763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.342768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-26T02:42:37.05588Z","caller":"traceutil/trace.go:171","msg":"trace[70211473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1873; }","duration":"900.481291ms","start":"2026-04-26T02:42:36.155376Z","end":"2026-04-26T02:42:37.055857Z","steps":["trace[70211473] 'agreement among raft nodes before linearized reading' (duration: 899.857819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.055935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:36.15536Z","time spent":"900.559992ms","remote":"127.0.0.1:37144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" "} {"level":"info","ts":"2026-04-26T02:42:37.132826Z","caller":"traceutil/trace.go:171","msg":"trace[1962604847] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"180.890739ms","start":"2026-04-26T02:42:36.951905Z","end":"2026-04-26T02:42:37.132796Z","steps":["trace[1962604847] 'process raft request' (duration: 180.720815ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:37.335714Z","caller":"traceutil/trace.go:171","msg":"trace[1336941155] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"202.912818ms","start":"2026-04-26T02:42:37.132767Z","end":"2026-04-26T02:42:37.33568Z","steps":["trace[1336941155] 'read index received' (duration: 151.439939ms)","trace[1336941155] 'applied index is now lower than readState.Index' (duration: 51.471009ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:37.335745Z","caller":"traceutil/trace.go:171","msg":"trace[2004157051] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"275.677371ms","start":"2026-04-26T02:42:37.060035Z","end":"2026-04-26T02:42:37.335712Z","steps":["trace[2004157051] 'process raft request' (duration: 224.240703ms)","trace[2004157051] 'compare' (duration: 51.129413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:37.335952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.61781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-26T02:42:37.33607Z","caller":"traceutil/trace.go:171","msg":"trace[478737946] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1875; }","duration":"275.775713ms","start":"2026-04-26T02:42:37.060265Z","end":"2026-04-26T02:42:37.336041Z","steps":["trace[478737946] 'agreement among raft nodes before linearized reading' (duration: 275.538149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.336135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.954799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:37.336141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.12574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b96d0ab3-6b1f-4eb4-82a6-6916f2f20169\" ","response":"range_response_count:1 size:2174"} {"level":"info","ts":"2026-04-26T02:42:37.336206Z","caller":"traceutil/trace.go:171","msg":"trace[528388131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"162.252754ms","start":"2026-04-26T02:42:37.173941Z","end":"2026-04-26T02:42:37.336194Z","steps":["trace[528388131] 'agreement among raft nodes before linearized reading' (duration: 161.959739ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:37.336273Z","caller":"traceutil/trace.go:171","msg":"trace[1994196787] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b96d0ab3-6b1f-4eb4-82a6-6916f2f20169; range_end:; response_count:1; response_revision:1875; }","duration":"276.268572ms","start":"2026-04-26T02:42:37.059977Z","end":"2026-04-26T02:42:37.336246Z","steps":["trace[1994196787] 'agreement among raft nodes before linearized reading' (duration: 275.909736ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:37.560598Z","caller":"traceutil/trace.go:171","msg":"trace[288080406] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"214.657071ms","start":"2026-04-26T02:42:37.345908Z","end":"2026-04-26T02:42:37.560565Z","steps":["trace[288080406] 'process raft request' (duration: 146.848282ms)","trace[288080406] 'compare' (duration: 67.441382ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:37.646294Z","caller":"traceutil/trace.go:171","msg":"trace[1764810481] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"124.886814ms","start":"2026-04-26T02:42:37.521355Z","end":"2026-04-26T02:42:37.646242Z","steps":["trace[1764810481] 'read index received' (duration: 29.540581ms)","trace[1764810481] 'applied index is now lower than readState.Index' (duration: 95.344483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:37.64664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.885446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T02:42:37.646721Z","caller":"traceutil/trace.go:171","msg":"trace[1424708709] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1877; }","duration":"113.018409ms","start":"2026-04-26T02:42:37.533686Z","end":"2026-04-26T02:42:37.646704Z","steps":["trace[1424708709] 'agreement among raft nodes before linearized reading' (duration: 112.824946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:37.646767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.289191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-26T02:42:37.646832Z","caller":"traceutil/trace.go:171","msg":"trace[277274061] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1877; }","duration":"125.516206ms","start":"2026-04-26T02:42:37.5213Z","end":"2026-04-26T02:42:37.646816Z","steps":["trace[277274061] 'agreement among raft nodes before linearized reading' (duration: 125.149749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.295175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.160158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-26T02:42:38.295289Z","caller":"traceutil/trace.go:171","msg":"trace[1629834323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1878; }","duration":"238.345332ms","start":"2026-04-26T02:42:38.056919Z","end":"2026-04-26T02:42:38.295264Z","steps":["trace[1629834323] 'range keys from in-memory index tree' (duration: 237.895354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.295296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.21027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-26T02:42:38.2954Z","caller":"traceutil/trace.go:171","msg":"trace[94322393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1878; }","duration":"238.345722ms","start":"2026-04-26T02:42:38.057029Z","end":"2026-04-26T02:42:38.295374Z","steps":["trace[94322393] 'range keys from in-memory index tree' (duration: 238.001265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.295864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.608236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T02:42:38.295902Z","caller":"traceutil/trace.go:171","msg":"trace[47774487] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1878; }","duration":"290.705238ms","start":"2026-04-26T02:42:38.005185Z","end":"2026-04-26T02:42:38.29589Z","steps":["trace[47774487] 'count revisions from in-memory index tree' (duration: 290.456324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.295853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.217014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:38.296308Z","caller":"traceutil/trace.go:171","msg":"trace[1266228424] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1878; }","duration":"498.379247ms","start":"2026-04-26T02:42:37.797562Z","end":"2026-04-26T02:42:38.295942Z","steps":["trace[1266228424] 'range keys from in-memory index tree' (duration: 498.141352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.297783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:37.797545Z","time spent":"500.213901ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-26T02:42:38.6673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303331635488001,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T02:42:38.990067Z","caller":"traceutil/trace.go:171","msg":"trace[1158784997] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"932.610582ms","start":"2026-04-26T02:42:38.057424Z","end":"2026-04-26T02:42:38.990035Z","steps":["trace[1158784997] 'process raft request' (duration: 932.420137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:38.990262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.057406Z","time spent":"932.758414ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:39.05778Z","caller":"traceutil/trace.go:171","msg":"trace[1093179709] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"754.436043ms","start":"2026-04-26T02:42:38.303308Z","end":"2026-04-26T02:42:39.057744Z","steps":["trace[1093179709] 'process raft request' (duration: 754.326671ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.058048Z","caller":"traceutil/trace.go:171","msg":"trace[1802307237] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1962; }","duration":"891.736143ms","start":"2026-04-26T02:42:38.16628Z","end":"2026-04-26T02:42:39.058017Z","steps":["trace[1802307237] 'read index received' (duration: 823.615649ms)","trace[1802307237] 'applied index is now lower than readState.Index' (duration: 68.118784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:39.058201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.303262Z","time spent":"754.679508ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:39.058306Z","caller":"traceutil/trace.go:171","msg":"trace[1298603629] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"753.108048ms","start":"2026-04-26T02:42:38.305183Z","end":"2026-04-26T02:42:39.058291Z","steps":["trace[1298603629] 'process raft request' (duration: 752.510157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.058444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.30517Z","time spent":"753.177229ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T02:42:39.0585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.685028ms","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-26T02:42:39.058652Z","caller":"traceutil/trace.go:171","msg":"trace[1676907018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1882; }","duration":"754.897073ms","start":"2026-04-26T02:42:38.303731Z","end":"2026-04-26T02:42:39.058628Z","steps":["trace[1676907018] 'agreement among raft nodes before linearized reading' (duration: 754.588186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.058824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.303719Z","time spent":"755.009125ms","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-26T02:42:39.058939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.188966ms","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-26T02:42:39.058992Z","caller":"traceutil/trace.go:171","msg":"trace[908904862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"109.288388ms","start":"2026-04-26T02:42:38.949691Z","end":"2026-04-26T02:42:39.058979Z","steps":["trace[908904862] 'agreement among raft nodes before linearized reading' (duration: 109.128045ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.058904Z","caller":"traceutil/trace.go:171","msg":"trace[1627077987] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"756.39599ms","start":"2026-04-26T02:42:38.302431Z","end":"2026-04-26T02:42:39.058827Z","steps":["trace[1627077987] 'process raft request' (duration: 728.339267ms)","trace[1627077987] 'compare' (duration: 26.502704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:39.059148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.3024Z","time spent":"756.702916ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:39.059167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.828284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:39.05938Z","caller":"traceutil/trace.go:171","msg":"trace[1694641503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"893.14959ms","start":"2026-04-26T02:42:38.166214Z","end":"2026-04-26T02:42:39.059364Z","steps":["trace[1694641503] 'agreement among raft nodes before linearized reading' (duration: 892.662501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.059438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.641943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-26T02:42:39.059517Z","caller":"traceutil/trace.go:171","msg":"trace[1036253435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1882; }","duration":"110.766766ms","start":"2026-04-26T02:42:38.948736Z","end":"2026-04-26T02:42:39.059503Z","steps":["trace[1036253435] 'agreement among raft nodes before linearized reading' (duration: 110.46964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.059633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.16619Z","time spent":"893.276643ms","remote":"127.0.0.1:36978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T02:42:39.059776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.892435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:39.060686Z","caller":"traceutil/trace.go:171","msg":"trace[688771532] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1882; }","duration":"752.839654ms","start":"2026-04-26T02:42:38.307819Z","end":"2026-04-26T02:42:39.060659Z","steps":["trace[688771532] 'agreement among raft nodes before linearized reading' (duration: 751.843124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.060767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:38.307812Z","time spent":"752.934865ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-26T02:42:39.353898Z","caller":"traceutil/trace.go:171","msg":"trace[1913501083] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"289.133937ms","start":"2026-04-26T02:42:39.064734Z","end":"2026-04-26T02:42:39.353868Z","steps":["trace[1913501083] 'process raft request' (duration: 288.996865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.354339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.893602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-26T02:42:39.354424Z","caller":"traceutil/trace.go:171","msg":"trace[154398978] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1886; }","duration":"240.089835ms","start":"2026-04-26T02:42:39.11432Z","end":"2026-04-26T02:42:39.35441Z","steps":["trace[154398978] 'agreement among raft nodes before linearized reading' (duration: 239.80999ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.353938Z","caller":"traceutil/trace.go:171","msg":"trace[1876748870] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"289.77069ms","start":"2026-04-26T02:42:39.064128Z","end":"2026-04-26T02:42:39.353899Z","steps":["trace[1876748870] 'process raft request' (duration: 289.445963ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.354052Z","caller":"traceutil/trace.go:171","msg":"trace[371935392] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"289.207949ms","start":"2026-04-26T02:42:39.064823Z","end":"2026-04-26T02:42:39.354031Z","steps":["trace[371935392] 'process raft request' (duration: 288.989914ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.354061Z","caller":"traceutil/trace.go:171","msg":"trace[1863606874] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1967; }","duration":"239.619107ms","start":"2026-04-26T02:42:39.114399Z","end":"2026-04-26T02:42:39.354018Z","steps":["trace[1863606874] 'read index received' (duration: 239.141047ms)","trace[1863606874] 'applied index is now lower than readState.Index' (duration: 475.27µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:39.421869Z","caller":"traceutil/trace.go:171","msg":"trace[1549826962] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"306.431247ms","start":"2026-04-26T02:42:39.115402Z","end":"2026-04-26T02:42:39.421833Z","steps":["trace[1549826962] 'process raft request' (duration: 306.178773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:39.421934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.216605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:39.422053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:39.115381Z","time spent":"306.58064ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T02:42:39.422072Z","caller":"traceutil/trace.go:171","msg":"trace[1430428511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1887; }","duration":"253.397469ms","start":"2026-04-26T02:42:39.168618Z","end":"2026-04-26T02:42:39.422015Z","steps":["trace[1430428511] 'agreement among raft nodes before linearized reading' (duration: 253.198985ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.558179Z","caller":"traceutil/trace.go:171","msg":"trace[783749057] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"134.432876ms","start":"2026-04-26T02:42:39.4237Z","end":"2026-04-26T02:42:39.558133Z","steps":["trace[783749057] 'read index received' (duration: 113.301465ms)","trace[783749057] 'applied index is now lower than readState.Index' (duration: 21.129701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:39.558405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.643941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:39.558452Z","caller":"traceutil/trace.go:171","msg":"trace[683249688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"134.803764ms","start":"2026-04-26T02:42:39.423637Z","end":"2026-04-26T02:42:39.55844Z","steps":["trace[683249688] 'agreement among raft nodes before linearized reading' (duration: 134.63391ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:39.558535Z","caller":"traceutil/trace.go:171","msg":"trace[1500635519] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"198.169948ms","start":"2026-04-26T02:42:39.360322Z","end":"2026-04-26T02:42:39.558492Z","steps":["trace[1500635519] 'process raft request' (duration: 176.699529ms)","trace[1500635519] 'compare' (duration: 20.681603ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:39.732827Z","caller":"traceutil/trace.go:171","msg":"trace[125142958] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"304.55517ms","start":"2026-04-26T02:42:39.428213Z","end":"2026-04-26T02:42:39.732768Z","steps":["trace[125142958] 'process raft request' (duration: 288.879352ms)","trace[125142958] 'compare' (duration: 15.457004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:39.733005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:39.42819Z","time spent":"304.717343ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T02:42:39.733516Z","caller":"traceutil/trace.go:171","msg":"trace[586549692] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"132.253495ms","start":"2026-04-26T02:42:39.601245Z","end":"2026-04-26T02:42:39.733498Z","steps":["trace[586549692] 'process raft request' (duration: 131.49357ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:52.108435Z","caller":"traceutil/trace.go:171","msg":"trace[1533369915] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"130.22825ms","start":"2026-04-26T02:42:51.978178Z","end":"2026-04-26T02:42:52.108407Z","steps":["trace[1533369915] 'process raft request' (duration: 128.690982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.397278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228000763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:53.397379Z","caller":"traceutil/trace.go:171","msg":"trace[2056563334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1977; }","duration":"1.228178568s","start":"2026-04-26T02:42:52.169178Z","end":"2026-04-26T02:42:53.397356Z","steps":["trace[2056563334] 'range keys from in-memory index tree' (duration: 1.22789164s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.397464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:52.169152Z","time spent":"1.228280371s","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T02:42:53.397625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.312164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-26T02:42:53.397716Z","caller":"traceutil/trace.go:171","msg":"trace[755335789] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1977; }","duration":"793.423726ms","start":"2026-04-26T02:42:52.604245Z","end":"2026-04-26T02:42:53.397668Z","steps":["trace[755335789] 'range keys from in-memory index tree' (duration: 793.089198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.397757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215641007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-26T02:42:53.397795Z","caller":"traceutil/trace.go:171","msg":"trace[429614573] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1977; }","duration":"1.215708828s","start":"2026-04-26T02:42:52.182076Z","end":"2026-04-26T02:42:53.397785Z","steps":["trace[429614573] 'range keys from in-memory index tree' (duration: 1.215461782s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.397762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:52.604232Z","time spent":"793.518969ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-26T02:42:53.397828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:52.182058Z","time spent":"1.21576421s","remote":"127.0.0.1:36992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-04-26T02:42:53.39728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.416051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:53.397983Z","caller":"traceutil/trace.go:171","msg":"trace[1098242590] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1977; }","duration":"912.218472ms","start":"2026-04-26T02:42:52.485754Z","end":"2026-04-26T02:42:53.397972Z","steps":["trace[1098242590] 'range keys from in-memory index tree' (duration: 911.297058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.398022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:52.485725Z","time spent":"912.288844ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-26T02:42:53.443458Z","caller":"traceutil/trace.go:171","msg":"trace[710840498] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"321.110813ms","start":"2026-04-26T02:42:53.122318Z","end":"2026-04-26T02:42:53.443429Z","steps":["trace[710840498] 'read index received' (duration: 320.9869ms)","trace[710840498] 'applied index is now lower than readState.Index' (duration: 122.443µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:53.443568Z","caller":"traceutil/trace.go:171","msg":"trace[1285107312] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"371.618806ms","start":"2026-04-26T02:42:53.071926Z","end":"2026-04-26T02:42:53.443545Z","steps":["trace[1285107312] 'process raft request' (duration: 371.307137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.443705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.071884Z","time spent":"371.736188ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:53.4438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.464132ms","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-26T02:42:53.443895Z","caller":"traceutil/trace.go:171","msg":"trace[306820355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1978; }","duration":"322.252171ms","start":"2026-04-26T02:42:53.121622Z","end":"2026-04-26T02:42:53.443875Z","steps":["trace[306820355] 'agreement among raft nodes before linearized reading' (duration: 321.958944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:53.443945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.121607Z","time spent":"322.325573ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T02:42:54.187845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.948106ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-26T02:42:54.188468Z","caller":"traceutil/trace.go:171","msg":"trace[189653031] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"738.291079ms","start":"2026-04-26T02:42:53.450151Z","end":"2026-04-26T02:42:54.188442Z","steps":["trace[189653031] 'process raft request' (duration: 738.221537ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.188496Z","caller":"traceutil/trace.go:171","msg":"trace[998343559] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"782.71964ms","start":"2026-04-26T02:42:53.405728Z","end":"2026-04-26T02:42:54.188447Z","steps":["trace[998343559] 'process raft request' (duration: 782.33936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.188549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.39996Z","time spent":"788.574597ms","remote":"127.0.0.1:36992","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-26T02:42:54.1888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.450118Z","time spent":"738.415183ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:54.18883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.260379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:54.188888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.405706Z","time spent":"783.07989ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T02:42:54.188918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064783736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T02:42:54.188915Z","caller":"traceutil/trace.go:171","msg":"trace[2146808618] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1980; }","duration":"784.415252ms","start":"2026-04-26T02:42:53.404481Z","end":"2026-04-26T02:42:54.188896Z","steps":["trace[2146808618] 'agreement among raft nodes before linearized reading' (duration: 784.015163ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.188963Z","caller":"traceutil/trace.go:171","msg":"trace[684976898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1980; }","duration":"1.064891149s","start":"2026-04-26T02:42:53.12406Z","end":"2026-04-26T02:42:54.188951Z","steps":["trace[684976898] 'agreement among raft nodes before linearized reading' (duration: 1.064509309s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.188995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.124035Z","time spent":"1.06495222s","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T02:42:54.188519Z","caller":"traceutil/trace.go:171","msg":"trace[612744349] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2064; }","duration":"744.945193ms","start":"2026-04-26T02:42:53.443545Z","end":"2026-04-26T02:42:54.18849Z","steps":["trace[612744349] 'read index received' (duration: 278.344471ms)","trace[612744349] 'applied index is now lower than readState.Index' (duration: 466.598102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:54.1891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.069223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T02:42:54.189116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.215257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-26T02:42:54.189134Z","caller":"traceutil/trace.go:171","msg":"trace[1825573417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"788.158296ms","start":"2026-04-26T02:42:53.400967Z","end":"2026-04-26T02:42:54.189125Z","steps":["trace[1825573417] 'agreement among raft nodes before linearized reading' (duration: 788.109405ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.189155Z","caller":"traceutil/trace.go:171","msg":"trace[1757985978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1980; }","duration":"763.315629ms","start":"2026-04-26T02:42:53.425828Z","end":"2026-04-26T02:42:54.189143Z","steps":["trace[1757985978] 'agreement among raft nodes before linearized reading' (duration: 763.188906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.189168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.400939Z","time spent":"788.222187ms","remote":"127.0.0.1:36978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T02:42:54.189194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.425799Z","time spent":"763.384771ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:54.189248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.140496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-26T02:42:54.189305Z","caller":"traceutil/trace.go:171","msg":"trace[1603226162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1980; }","duration":"380.25186ms","start":"2026-04-26T02:42:53.80904Z","end":"2026-04-26T02:42:54.189292Z","steps":["trace[1603226162] 'agreement among raft nodes before linearized reading' (duration: 380.102126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.189353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.809016Z","time spent":"380.326401ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-26T02:42:54.189372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.130899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-26T02:42:54.189406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.193111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:54.189421Z","caller":"traceutil/trace.go:171","msg":"trace[1121104258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1980; }","duration":"766.217771ms","start":"2026-04-26T02:42:53.423183Z","end":"2026-04-26T02:42:54.189401Z","steps":["trace[1121104258] 'agreement among raft nodes before linearized reading' (duration: 765.992756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.189456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.423172Z","time spent":"766.278013ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T02:42:54.18899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.404453Z","time spent":"784.524655ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-26T02:42:54.189452Z","caller":"traceutil/trace.go:171","msg":"trace[1642317189] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1980; }","duration":"860.295734ms","start":"2026-04-26T02:42:53.329145Z","end":"2026-04-26T02:42:54.189441Z","steps":["trace[1642317189] 'agreement among raft nodes before linearized reading' (duration: 860.225103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.189543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.194584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-26T02:42:54.189569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.329121Z","time spent":"860.433007ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-26T02:42:54.189577Z","caller":"traceutil/trace.go:171","msg":"trace[1168386964] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1980; }","duration":"948.283296ms","start":"2026-04-26T02:42:53.241283Z","end":"2026-04-26T02:42:54.189566Z","steps":["trace[1168386964] 'agreement among raft nodes before linearized reading' (duration: 948.188824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T02:42:54.189608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T02:42:53.241253Z","time spent":"948.347618ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-26T02:42:54.487884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.881837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T02:42:54.488277Z","caller":"traceutil/trace.go:171","msg":"trace[426876722] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2067; }","duration":"289.689554ms","start":"2026-04-26T02:42:54.198568Z","end":"2026-04-26T02:42:54.488258Z","steps":["trace[426876722] 'read index received' (duration: 145.288434ms)","trace[426876722] 'applied index is now lower than readState.Index' (duration: 144.39998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T02:42:54.488391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.821627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T02:42:54.488427Z","caller":"traceutil/trace.go:171","msg":"trace[59585941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1986; }","duration":"289.882729ms","start":"2026-04-26T02:42:54.198535Z","end":"2026-04-26T02:42:54.488417Z","steps":["trace[59585941] 'agreement among raft nodes before linearized reading' (duration: 289.793757ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.48851Z","caller":"traceutil/trace.go:171","msg":"trace[1503749372] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"293.487658ms","start":"2026-04-26T02:42:54.194856Z","end":"2026-04-26T02:42:54.488344Z","steps":["trace[1503749372] 'process raft request' (duration: 293.214952ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.488711Z","caller":"traceutil/trace.go:171","msg":"trace[512616593] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"291.731534ms","start":"2026-04-26T02:42:54.196964Z","end":"2026-04-26T02:42:54.488696Z","steps":["trace[512616593] 'process raft request' (duration: 291.221801ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.488702Z","caller":"traceutil/trace.go:171","msg":"trace[628643241] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"292.461783ms","start":"2026-04-26T02:42:54.196113Z","end":"2026-04-26T02:42:54.488575Z","steps":["trace[628643241] 'process raft request' (duration: 292.025692ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.48893Z","caller":"traceutil/trace.go:171","msg":"trace[212201264] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"294.498823ms","start":"2026-04-26T02:42:54.194355Z","end":"2026-04-26T02:42:54.488854Z","steps":["trace[212201264] 'process raft request' (duration: 293.657332ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:54.48904Z","caller":"traceutil/trace.go:171","msg":"trace[1746454841] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"296.916303ms","start":"2026-04-26T02:42:54.192112Z","end":"2026-04-26T02:42:54.489028Z","steps":["trace[1746454841] 'process raft request' (duration: 151.779234ms)","trace[1746454841] 'compare' (duration: 143.735104ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T02:42:54.48902Z","caller":"traceutil/trace.go:171","msg":"trace[1032416595] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"293.725214ms","start":"2026-04-26T02:42:54.19528Z","end":"2026-04-26T02:42:54.489006Z","steps":["trace[1032416595] 'process raft request' (duration: 292.826593ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T02:42:55.56426Z","caller":"traceutil/trace.go:171","msg":"trace[1256461786] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"114.792136ms","start":"2026-04-26T02:42:55.449429Z","end":"2026-04-26T02:42:55.564221Z","steps":["trace[1256461786] 'process raft request' (duration: 114.622802ms)"],"step_count":1}