{"level":"warn","ts":"2026-05-07T03:23:18.288906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:23:18.289074Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T03:23:18.289202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:23:18.289221Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-07T03:23:18.289257Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:23:18.290632Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-07T03:23:18.290781Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T03:23:18.29522Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.019086ms"} {"level":"info","ts":"2026-05-07T03:23:18.309707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-07T03:23:18.309832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:23:18.309886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-07T03:23:18.309909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:23:18.309935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-07T03:23:18.309987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-07T03:23:18.325084Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:23:18.333389Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:23:18.335628Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T03:23:18.338033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:23:18.345236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:23:18.345363Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:23:18.34538Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:23:18.348091Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:23:18.348634Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:23:18.348844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:23:18.348878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:23:18.349375Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-07T03:23:18.349388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-07T03:23:18.350528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-07T03:23:18.350644Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-07T03:23:18.710926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:23:18.710999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:23:18.711051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-07T03:23:18.711074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:23:18.711091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-07T03:23:18.711109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-07T03:23:18.711129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-07T03:23:18.713171Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:23:18.713209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:23:18.713306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:23:18.713465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:23:18.713794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:23:18.713842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:23:18.716407Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:23:18.71668Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:23:18.716744Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:23:18.717868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-07T03:23:18.718537Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-07T03:23:36.833893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.452649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:23:36.833988Z","caller":"traceutil/trace.go:171","msg":"trace[287565558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"326.607444ms","start":"2026-05-07T03:23:36.507362Z","end":"2026-05-07T03:23:36.83397Z","steps":["trace[287565558] 'range keys from in-memory index tree' (duration: 326.271055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:36.834041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:36.507345Z","time spent":"326.687065ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:23:37.072354Z","caller":"traceutil/trace.go:171","msg":"trace[1037447480] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"233.962566ms","start":"2026-05-07T03:23:36.838367Z","end":"2026-05-07T03:23:37.072329Z","steps":["trace[1037447480] 'process raft request' (duration: 233.753071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:37.765069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.081212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T03:23:37.765241Z","caller":"traceutil/trace.go:171","msg":"trace[1194750585] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"744.41299ms","start":"2026-05-07T03:23:37.020813Z","end":"2026-05-07T03:23:37.765226Z","steps":["trace[1194750585] 'read index received' (duration: 51.440397ms)","trace[1194750585] 'applied index is now lower than readState.Index' (duration: 692.971243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:23:37.765334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.017534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:23:37.765351Z","caller":"traceutil/trace.go:171","msg":"trace[1571643660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"471.079786ms","start":"2026-05-07T03:23:37.294265Z","end":"2026-05-07T03:23:37.765345Z","steps":["trace[1571643660] 'agreement among raft nodes before linearized reading' (duration: 471.031915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:37.765381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.542203ms","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-05-07T03:23:37.765451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:37.294245Z","time spent":"471.195308ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:23:37.765475Z","caller":"traceutil/trace.go:171","msg":"trace[1638127024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"744.684547ms","start":"2026-05-07T03:23:37.020766Z","end":"2026-05-07T03:23:37.76545Z","steps":["trace[1638127024] 'agreement among raft nodes before linearized reading' (duration: 744.497722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:37.765523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:37.02074Z","time spent":"744.773638ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:23:48.019907Z","caller":"traceutil/trace.go:171","msg":"trace[1139702009] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"360.976512ms","start":"2026-05-07T03:23:47.658906Z","end":"2026-05-07T03:23:48.019883Z","steps":["trace[1139702009] 'process raft request' (duration: 360.792688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.02038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.658884Z","time spent":"361.110215ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.028086Z","caller":"traceutil/trace.go:171","msg":"trace[542427590] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"368.690046ms","start":"2026-05-07T03:23:47.659359Z","end":"2026-05-07T03:23:48.028049Z","steps":["trace[542427590] 'process raft request' (duration: 368.45665ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.028298Z","caller":"traceutil/trace.go:171","msg":"trace[1304487134] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"359.712432ms","start":"2026-05-07T03:23:47.668524Z","end":"2026-05-07T03:23:48.028237Z","steps":["trace[1304487134] 'process raft request' (duration: 359.421076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.028356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.65935Z","time spent":"368.885401ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.028379Z","caller":"traceutil/trace.go:171","msg":"trace[1887468625] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"358.515583ms","start":"2026-05-07T03:23:47.669832Z","end":"2026-05-07T03:23:48.028347Z","steps":["trace[1887468625] 'process raft request' (duration: 358.220466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.028436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.668507Z","time spent":"359.865816ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:23:48.028478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.66982Z","time spent":"358.610056ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.028516Z","caller":"traceutil/trace.go:171","msg":"trace[1019496975] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"358.578285ms","start":"2026-05-07T03:23:47.669889Z","end":"2026-05-07T03:23:48.028467Z","steps":["trace[1019496975] 'process raft request' (duration: 358.214006ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.028634Z","caller":"traceutil/trace.go:171","msg":"trace[1566761809] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"358.748728ms","start":"2026-05-07T03:23:47.669872Z","end":"2026-05-07T03:23:48.028621Z","steps":["trace[1566761809] 'process raft request' (duration: 358.271657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.028646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.669884Z","time spent":"358.686327ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:23:48.028679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.669848Z","time spent":"358.8096ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.029917Z","caller":"traceutil/trace.go:171","msg":"trace[1277203282] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"359.978827ms","start":"2026-05-07T03:23:47.669914Z","end":"2026-05-07T03:23:48.029893Z","steps":["trace[1277203282] 'process raft request' (duration: 358.37854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.029959Z","caller":"traceutil/trace.go:171","msg":"trace[114611553] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"356.609818ms","start":"2026-05-07T03:23:47.673314Z","end":"2026-05-07T03:23:48.029923Z","steps":["trace[114611553] 'process raft request' (duration: 356.400723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.030014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.673304Z","time spent":"356.68435ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:23:48.030031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.669909Z","time spent":"360.07277ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.03015Z","caller":"traceutil/trace.go:171","msg":"trace[1588184887] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"323.623271ms","start":"2026-05-07T03:23:47.706516Z","end":"2026-05-07T03:23:48.030139Z","steps":["trace[1588184887] 'process raft request' (duration: 323.393116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.030147Z","caller":"traceutil/trace.go:171","msg":"trace[105067948] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:486; }","duration":"219.092841ms","start":"2026-05-07T03:23:47.811036Z","end":"2026-05-07T03:23:48.030129Z","steps":["trace[105067948] 'read index received' (duration: 208.886348ms)","trace[105067948] 'applied index is now lower than readState.Index' (duration: 10.205603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:23:48.030197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.706488Z","time spent":"323.684093ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:23:48.030264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.215094ms","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-05-07T03:23:48.03028Z","caller":"traceutil/trace.go:171","msg":"trace[120079518] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"341.779885ms","start":"2026-05-07T03:23:47.688491Z","end":"2026-05-07T03:23:48.03027Z","steps":["trace[120079518] 'process raft request' (duration: 341.345915ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.030296Z","caller":"traceutil/trace.go:171","msg":"trace[1357049950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"219.280236ms","start":"2026-05-07T03:23:47.811006Z","end":"2026-05-07T03:23:48.030286Z","steps":["trace[1357049950] 'agreement among raft nodes before linearized reading' (duration: 219.185203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.030322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.688476Z","time spent":"341.828336ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:48.030414Z","caller":"traceutil/trace.go:171","msg":"trace[310737381] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"215.073074ms","start":"2026-05-07T03:23:47.815333Z","end":"2026-05-07T03:23:48.030406Z","steps":["trace[310737381] 'process raft request' (duration: 214.698246ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:48.030492Z","caller":"traceutil/trace.go:171","msg":"trace[1660149741] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"312.169789ms","start":"2026-05-07T03:23:47.718315Z","end":"2026-05-07T03:23:48.030485Z","steps":["trace[1660149741] 'process raft request' (duration: 311.645857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:48.030528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:47.718298Z","time spent":"312.21206ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:23:54.658Z","caller":"traceutil/trace.go:171","msg":"trace[423615402] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"361.684769ms","start":"2026-05-07T03:23:54.296293Z","end":"2026-05-07T03:23:54.657978Z","steps":["trace[423615402] 'read index received' (duration: 360.352107ms)","trace[423615402] 'applied index is now lower than readState.Index' (duration: 1.331531ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:23:54.658104Z","caller":"traceutil/trace.go:171","msg":"trace[991886897] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"443.949919ms","start":"2026-05-07T03:23:54.214126Z","end":"2026-05-07T03:23:54.658076Z","steps":["trace[991886897] 'process raft request' (duration: 442.795232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:54.658155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.849893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:23:54.658193Z","caller":"traceutil/trace.go:171","msg":"trace[1484161314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"361.936474ms","start":"2026-05-07T03:23:54.296243Z","end":"2026-05-07T03:23:54.65818Z","steps":["trace[1484161314] 'agreement among raft nodes before linearized reading' (duration: 361.860903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:54.658228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:54.296203Z","time spent":"362.017286ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:23:54.658264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.583001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v5jhf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-07T03:23:54.6583Z","caller":"traceutil/trace.go:171","msg":"trace[1564879683] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v5jhf; range_end:; response_count:1; response_revision:576; }","duration":"245.658923ms","start":"2026-05-07T03:23:54.412629Z","end":"2026-05-07T03:23:54.658288Z","steps":["trace[1564879683] 'agreement among raft nodes before linearized reading' (duration: 245.53236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:54.658342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.145436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:23:54.658398Z","caller":"traceutil/trace.go:171","msg":"trace[884609862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"173.251338ms","start":"2026-05-07T03:23:54.48513Z","end":"2026-05-07T03:23:54.658381Z","steps":["trace[884609862] 'agreement among raft nodes before linearized reading' (duration: 173.083124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:54.658429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:54.214099Z","time spent":"444.072182ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:23:54.658493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.082304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:23:54.658525Z","caller":"traceutil/trace.go:171","msg":"trace[425230019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"241.158526ms","start":"2026-05-07T03:23:54.417355Z","end":"2026-05-07T03:23:54.658514Z","steps":["trace[425230019] 'agreement among raft nodes before linearized reading' (duration: 241.084134ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:54.967746Z","caller":"traceutil/trace.go:171","msg":"trace[1006140925] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"302.612791ms","start":"2026-05-07T03:23:54.665108Z","end":"2026-05-07T03:23:54.967721Z","steps":["trace[1006140925] 'process raft request' (duration: 302.375916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:54.967921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:54.665088Z","time spent":"302.772494ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:23:54.967748Z","caller":"traceutil/trace.go:171","msg":"trace[225158962] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"297.431557ms","start":"2026-05-07T03:23:54.670286Z","end":"2026-05-07T03:23:54.967718Z","steps":["trace[225158962] 'process raft request' (duration: 297.371156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:56.282013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185563712s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:23:56.282153Z","caller":"traceutil/trace.go:171","msg":"trace[30689142] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"1.110668927s","start":"2026-05-07T03:23:55.171467Z","end":"2026-05-07T03:23:56.282136Z","steps":["trace[30689142] 'read index received' (duration: 62.951µs)","trace[30689142] 'applied index is now lower than readState.Index' (duration: 1.110604866s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:23:56.282534Z","caller":"traceutil/trace.go:171","msg":"trace[1663955453] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.570090455s","start":"2026-05-07T03:23:54.712427Z","end":"2026-05-07T03:23:56.282518Z","steps":["trace[1663955453] 'process raft request' (duration: 383.938619ms)","trace[1663955453] 'compare' (duration: 1.185093661s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:23:56.282607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:54.712407Z","time spent":"1.570167467s","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:23:56.282608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111104297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T03:23:56.282713Z","caller":"traceutil/trace.go:171","msg":"trace[730490829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"1.111280091s","start":"2026-05-07T03:23:55.171412Z","end":"2026-05-07T03:23:56.282692Z","steps":["trace[730490829] 'agreement among raft nodes before linearized reading' (duration: 1.110858031s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:56.282754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.739668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T03:23:56.282782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:55.171388Z","time spent":"1.111382064s","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:23:56.282603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.560326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:23:56.282813Z","caller":"traceutil/trace.go:171","msg":"trace[1527440866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"149.83322ms","start":"2026-05-07T03:23:56.132954Z","end":"2026-05-07T03:23:56.282787Z","steps":["trace[1527440866] 'agreement among raft nodes before linearized reading' (duration: 149.700277ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:56.28287Z","caller":"traceutil/trace.go:171","msg":"trace[640730397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"985.821642ms","start":"2026-05-07T03:23:55.297017Z","end":"2026-05-07T03:23:56.282839Z","steps":["trace[640730397] 'agreement among raft nodes before linearized reading' (duration: 985.519185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:56.28297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:55.296991Z","time spent":"985.947096ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:23:57.801817Z","caller":"traceutil/trace.go:171","msg":"trace[1616163146] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"255.219932ms","start":"2026-05-07T03:23:57.54658Z","end":"2026-05-07T03:23:57.8018Z","steps":["trace[1616163146] 'process raft request' (duration: 254.72493ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:57.804555Z","caller":"traceutil/trace.go:171","msg":"trace[1335532422] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:627; }","duration":"130.513869ms","start":"2026-05-07T03:23:57.674028Z","end":"2026-05-07T03:23:57.804542Z","steps":["trace[1335532422] 'read index received' (duration: 127.265442ms)","trace[1335532422] 'applied index is now lower than readState.Index' (duration: 3.247757ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:23:57.804594Z","caller":"traceutil/trace.go:171","msg":"trace[820114315] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"252.117778ms","start":"2026-05-07T03:23:57.552464Z","end":"2026-05-07T03:23:57.804582Z","steps":["trace[820114315] 'process raft request' (duration: 251.990195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:57.804931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.89134ms","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-05-07T03:23:57.804968Z","caller":"traceutil/trace.go:171","msg":"trace[986660182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"130.967621ms","start":"2026-05-07T03:23:57.673993Z","end":"2026-05-07T03:23:57.804961Z","steps":["trace[986660182] 'agreement among raft nodes before linearized reading' (duration: 130.896059ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:57.804629Z","caller":"traceutil/trace.go:171","msg":"trace[1758709464] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"257.615028ms","start":"2026-05-07T03:23:57.547008Z","end":"2026-05-07T03:23:57.804623Z","steps":["trace[1758709464] 'process raft request' (duration: 257.333721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:57.805064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.011112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:23:57.805129Z","caller":"traceutil/trace.go:171","msg":"trace[544468487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:606; }","duration":"131.120475ms","start":"2026-05-07T03:23:57.673993Z","end":"2026-05-07T03:23:57.805114Z","steps":["trace[544468487] 'agreement among raft nodes before linearized reading' (duration: 131.005982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:24:11.36793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.626359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-07T03:24:11.368091Z","caller":"traceutil/trace.go:171","msg":"trace[751355483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"330.819033ms","start":"2026-05-07T03:24:11.037252Z","end":"2026-05-07T03:24:11.368071Z","steps":["trace[751355483] 'range keys from in-memory index tree' (duration: 330.490135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:24:11.368157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:24:11.03724Z","time spent":"330.905205ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:24:11.596638Z","caller":"traceutil/trace.go:171","msg":"trace[1900161189] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"150.099018ms","start":"2026-05-07T03:24:11.446505Z","end":"2026-05-07T03:24:11.596604Z","steps":["trace[1900161189] 'process raft request' (duration: 149.883723ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:24:46.840318Z","caller":"traceutil/trace.go:171","msg":"trace[272991400] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"286.84771ms","start":"2026-05-07T03:24:46.553444Z","end":"2026-05-07T03:24:46.840292Z","steps":["trace[272991400] 'process raft request' (duration: 286.657065ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:24:46.896596Z","caller":"traceutil/trace.go:171","msg":"trace[2056853695] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"192.21012ms","start":"2026-05-07T03:24:46.704359Z","end":"2026-05-07T03:24:46.896569Z","steps":["trace[2056853695] 'process raft request' (duration: 192.139249ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:24:46.89706Z","caller":"traceutil/trace.go:171","msg":"trace[1669316385] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"343.07359ms","start":"2026-05-07T03:24:46.553975Z","end":"2026-05-07T03:24:46.897049Z","steps":["trace[1669316385] 'process raft request' (duration: 338.782506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:24:46.897155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:24:46.553962Z","time spent":"343.124621ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:07.895167Z","caller":"traceutil/trace.go:171","msg":"trace[1122941676] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"107.238785ms","start":"2026-05-07T03:25:07.787903Z","end":"2026-05-07T03:25:07.895142Z","steps":["trace[1122941676] 'process raft request' (duration: 107.112271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:08.422051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.841977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:08.422138Z","caller":"traceutil/trace.go:171","msg":"trace[1743317914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"123.945629ms","start":"2026-05-07T03:25:08.298172Z","end":"2026-05-07T03:25:08.422118Z","steps":["trace[1743317914] 'range keys from in-memory index tree' (duration: 123.680253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:08.422221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.510087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T03:25:08.422354Z","caller":"traceutil/trace.go:171","msg":"trace[2045010109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"211.731873ms","start":"2026-05-07T03:25:08.210595Z","end":"2026-05-07T03:25:08.422327Z","steps":["trace[2045010109] 'range keys from in-memory index tree' (duration: 211.252321ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:09.207658Z","caller":"traceutil/trace.go:171","msg":"trace[1845669726] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"226.232943ms","start":"2026-05-07T03:25:08.981397Z","end":"2026-05-07T03:25:09.20763Z","steps":["trace[1845669726] 'read index received' (duration: 201.775161ms)","trace[1845669726] 'applied index is now lower than readState.Index' (duration: 24.456742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:09.207886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.479918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:25:09.207737Z","caller":"traceutil/trace.go:171","msg":"trace[310741867] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"234.77631ms","start":"2026-05-07T03:25:08.972935Z","end":"2026-05-07T03:25:09.207711Z","steps":["trace[310741867] 'process raft request' (duration: 210.339119ms)","trace[310741867] 'compare' (duration: 24.242716ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:09.207967Z","caller":"traceutil/trace.go:171","msg":"trace[496382236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"226.602822ms","start":"2026-05-07T03:25:08.981349Z","end":"2026-05-07T03:25:09.207952Z","steps":["trace[496382236] 'agreement among raft nodes before linearized reading' (duration: 226.433778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:09.208459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.534431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:25:09.208933Z","caller":"traceutil/trace.go:171","msg":"trace[417955646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"170.064693ms","start":"2026-05-07T03:25:09.038849Z","end":"2026-05-07T03:25:09.208914Z","steps":["trace[417955646] 'agreement among raft nodes before linearized reading' (duration: 169.462659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:09.643755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.397719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:09.643859Z","caller":"traceutil/trace.go:171","msg":"trace[365325873] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"280.594728ms","start":"2026-05-07T03:25:09.363246Z","end":"2026-05-07T03:25:09.643841Z","steps":["trace[365325873] 'read index received' (duration: 53.201µs)","trace[365325873] 'applied index is now lower than readState.Index' (duration: 280.540437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:09.643951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.71354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:09.643951Z","caller":"traceutil/trace.go:171","msg":"trace[1796053196] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"429.88164ms","start":"2026-05-07T03:25:09.214036Z","end":"2026-05-07T03:25:09.643918Z","steps":["trace[1796053196] 'process raft request' (duration: 107.219944ms)","trace[1796053196] 'compare' (duration: 322.263146ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:09.64398Z","caller":"traceutil/trace.go:171","msg":"trace[540983209] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1037; }","duration":"280.742621ms","start":"2026-05-07T03:25:09.363226Z","end":"2026-05-07T03:25:09.643969Z","steps":["trace[540983209] 'agreement among raft nodes before linearized reading' (duration: 280.666649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:09.644142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:09.214018Z","time spent":"430.033024ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:10.218366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.370777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:25:10.218467Z","caller":"traceutil/trace.go:171","msg":"trace[892433089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"319.52576ms","start":"2026-05-07T03:25:09.89892Z","end":"2026-05-07T03:25:10.218446Z","steps":["trace[892433089] 'range keys from in-memory index tree' (duration: 319.185152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:10.21851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:09.898896Z","time spent":"319.604622ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:25:10.598867Z","caller":"traceutil/trace.go:171","msg":"trace[1454650441] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"121.722315ms","start":"2026-05-07T03:25:10.477117Z","end":"2026-05-07T03:25:10.598839Z","steps":["trace[1454650441] 'process raft request' (duration: 121.542541ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:11.726002Z","caller":"traceutil/trace.go:171","msg":"trace[1253611280] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"439.613975ms","start":"2026-05-07T03:25:11.286366Z","end":"2026-05-07T03:25:11.72598Z","steps":["trace[1253611280] 'read index received' (duration: 439.38645ms)","trace[1253611280] 'applied index is now lower than readState.Index' (duration: 226.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:11.726079Z","caller":"traceutil/trace.go:171","msg":"trace[1801849914] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"513.236576ms","start":"2026-05-07T03:25:11.212819Z","end":"2026-05-07T03:25:11.726056Z","steps":["trace[1801849914] 'process raft request' (duration: 513.018411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:11.726221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.212787Z","time spent":"513.340899ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:11.726223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.881522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:25:11.726308Z","caller":"traceutil/trace.go:171","msg":"trace[1380960256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1041; }","duration":"347.063306ms","start":"2026-05-07T03:25:11.379231Z","end":"2026-05-07T03:25:11.726294Z","steps":["trace[1380960256] 'agreement among raft nodes before linearized reading' (duration: 346.874901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:11.726303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.919392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T03:25:11.72638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.379217Z","time spent":"347.125438ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:25:11.726391Z","caller":"traceutil/trace.go:171","msg":"trace[1338939539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"440.060416ms","start":"2026-05-07T03:25:11.286309Z","end":"2026-05-07T03:25:11.72637Z","steps":["trace[1338939539] 'agreement among raft nodes before linearized reading' (duration: 439.787439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:11.726441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.286295Z","time spent":"440.135698ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:25:12.353932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.701742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:12.354007Z","caller":"traceutil/trace.go:171","msg":"trace[1440674768] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"623.271508ms","start":"2026-05-07T03:25:11.730721Z","end":"2026-05-07T03:25:12.353992Z","steps":["trace[1440674768] 'process raft request' (duration: 208.439902ms)","trace[1440674768] 'compare' (duration: 414.364725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:12.354041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.730696Z","time spent":"623.329318ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:12.358647Z","caller":"traceutil/trace.go:171","msg":"trace[1743207110] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1081; }","duration":"574.218241ms","start":"2026-05-07T03:25:11.784416Z","end":"2026-05-07T03:25:12.358634Z","steps":["trace[1743207110] 'read index received' (duration: 154.761794ms)","trace[1743207110] 'applied index is now lower than readState.Index' (duration: 419.455637ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:12.358794Z","caller":"traceutil/trace.go:171","msg":"trace[1346630184] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"627.955411ms","start":"2026-05-07T03:25:11.730757Z","end":"2026-05-07T03:25:12.358713Z","steps":["trace[1346630184] 'process raft request' (duration: 627.824188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:12.358834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.730735Z","time spent":"628.075884ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:12.359139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.717943ms","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-05-07T03:25:12.35911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.248407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:25:12.359265Z","caller":"traceutil/trace.go:171","msg":"trace[515433947] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1043; }","duration":"556.39999ms","start":"2026-05-07T03:25:11.802783Z","end":"2026-05-07T03:25:12.359183Z","steps":["trace[515433947] 'agreement among raft nodes before linearized reading' (duration: 556.124894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:12.359334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.136159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:25:12.359412Z","caller":"traceutil/trace.go:171","msg":"trace[641717095] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1043; }","duration":"283.259042ms","start":"2026-05-07T03:25:12.076135Z","end":"2026-05-07T03:25:12.359394Z","steps":["trace[641717095] 'agreement among raft nodes before linearized reading' (duration: 283.14337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:12.359334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.802763Z","time spent":"556.559895ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-05-07T03:25:12.35916Z","caller":"traceutil/trace.go:171","msg":"trace[1221560738] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1043; }","duration":"574.768825ms","start":"2026-05-07T03:25:11.784384Z","end":"2026-05-07T03:25:12.359153Z","steps":["trace[1221560738] 'agreement among raft nodes before linearized reading' (duration: 574.731314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:12.359639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:11.784375Z","time spent":"575.255256ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:25:12.535433Z","caller":"traceutil/trace.go:171","msg":"trace[180727496] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"171.484718ms","start":"2026-05-07T03:25:12.363881Z","end":"2026-05-07T03:25:12.535365Z","steps":["trace[180727496] 'process raft request' (duration: 149.158298ms)","trace[180727496] 'compare' (duration: 22.175547ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:12.538123Z","caller":"traceutil/trace.go:171","msg":"trace[1736540062] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"133.660624ms","start":"2026-05-07T03:25:12.404436Z","end":"2026-05-07T03:25:12.538097Z","steps":["trace[1736540062] 'process raft request' (duration: 133.51019ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:23.980403Z","caller":"traceutil/trace.go:171","msg":"trace[958346443] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"107.771148ms","start":"2026-05-07T03:25:23.872608Z","end":"2026-05-07T03:25:23.980379Z","steps":["trace[958346443] 'process raft request' (duration: 107.562763ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:23.980595Z","caller":"traceutil/trace.go:171","msg":"trace[1923796602] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1175; }","duration":"102.420348ms","start":"2026-05-07T03:25:23.87815Z","end":"2026-05-07T03:25:23.980571Z","steps":["trace[1923796602] 'read index received' (duration: 55.36624ms)","trace[1923796602] 'applied index is now lower than readState.Index' (duration: 47.052658ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:23.980678Z","caller":"traceutil/trace.go:171","msg":"trace[1219920562] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"107.03432ms","start":"2026-05-07T03:25:23.873633Z","end":"2026-05-07T03:25:23.980667Z","steps":["trace[1219920562] 'process raft request' (duration: 106.708052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:23.980749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.549862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:23.980794Z","caller":"traceutil/trace.go:171","msg":"trace[824846831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"102.653424ms","start":"2026-05-07T03:25:23.878126Z","end":"2026-05-07T03:25:23.980779Z","steps":["trace[824846831] 'agreement among raft nodes before linearized reading' (duration: 102.542732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:24.946895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.63274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:25:24.946964Z","caller":"traceutil/trace.go:171","msg":"trace[2071618779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"359.708032ms","start":"2026-05-07T03:25:24.587243Z","end":"2026-05-07T03:25:24.946951Z","steps":["trace[2071618779] 'range keys from in-memory index tree' (duration: 359.514546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:24.946991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:24.587185Z","time spent":"359.798904ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:25:24.947277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.341633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:25:24.947304Z","caller":"traceutil/trace.go:171","msg":"trace[73572036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1135; }","duration":"285.410855ms","start":"2026-05-07T03:25:24.661886Z","end":"2026-05-07T03:25:24.947297Z","steps":["trace[73572036] 'range keys from in-memory index tree' (duration: 285.225281ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:25.361189Z","caller":"traceutil/trace.go:171","msg":"trace[1580030967] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"410.5022ms","start":"2026-05-07T03:25:24.950662Z","end":"2026-05-07T03:25:25.361165Z","steps":["trace[1580030967] 'process raft request' (duration: 410.349407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:25.361333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:24.950641Z","time spent":"410.616152ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:25.410159Z","caller":"traceutil/trace.go:171","msg":"trace[2067771101] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1180; }","duration":"113.893985ms","start":"2026-05-07T03:25:25.296238Z","end":"2026-05-07T03:25:25.410132Z","steps":["trace[2067771101] 'read index received' (duration: 64.792517ms)","trace[2067771101] 'applied index is now lower than readState.Index' (duration: 49.099978ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:25.41031Z","caller":"traceutil/trace.go:171","msg":"trace[612397880] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"458.325828ms","start":"2026-05-07T03:25:24.951955Z","end":"2026-05-07T03:25:25.41028Z","steps":["trace[612397880] 'process raft request' (duration: 458.00199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:25.410359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.09336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:25.410412Z","caller":"traceutil/trace.go:171","msg":"trace[1030219156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"114.200182ms","start":"2026-05-07T03:25:25.296194Z","end":"2026-05-07T03:25:25.410394Z","steps":["trace[1030219156] 'agreement among raft nodes before linearized reading' (duration: 114.066389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:25.41045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:24.951943Z","time spent":"458.42204ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:25.410485Z","caller":"traceutil/trace.go:171","msg":"trace[1299075609] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"333.298143ms","start":"2026-05-07T03:25:25.077144Z","end":"2026-05-07T03:25:25.410442Z","steps":["trace[1299075609] 'process raft request' (duration: 332.933634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:25.410661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:25.077116Z","time spent":"333.455637ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:38.278121Z","caller":"traceutil/trace.go:171","msg":"trace[466569142] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"231.299211ms","start":"2026-05-07T03:25:38.046804Z","end":"2026-05-07T03:25:38.278104Z","steps":["trace[466569142] 'read index received' (duration: 231.168508ms)","trace[466569142] 'applied index is now lower than readState.Index' (duration: 129.973µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:38.278272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.458434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T03:25:38.278292Z","caller":"traceutil/trace.go:171","msg":"trace[1194366445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"231.531856ms","start":"2026-05-07T03:25:38.046753Z","end":"2026-05-07T03:25:38.278285Z","steps":["trace[1194366445] 'agreement among raft nodes before linearized reading' (duration: 231.416214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:38.278252Z","caller":"traceutil/trace.go:171","msg":"trace[1813163148] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"233.520686ms","start":"2026-05-07T03:25:38.044695Z","end":"2026-05-07T03:25:38.278216Z","steps":["trace[1813163148] 'process raft request' (duration: 233.29643ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:40.732108Z","caller":"traceutil/trace.go:171","msg":"trace[951501739] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"145.145616ms","start":"2026-05-07T03:25:40.58693Z","end":"2026-05-07T03:25:40.732075Z","steps":["trace[951501739] 'process raft request' (duration: 54.617152ms)","trace[951501739] 'compare' (duration: 90.38122ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:41.163981Z","caller":"traceutil/trace.go:171","msg":"trace[158663055] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"210.827908ms","start":"2026-05-07T03:25:40.953136Z","end":"2026-05-07T03:25:41.163964Z","steps":["trace[158663055] 'process raft request' (duration: 210.724236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:41.65044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.670005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:41.65057Z","caller":"traceutil/trace.go:171","msg":"trace[795511205] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"355.79394ms","start":"2026-05-07T03:25:41.294758Z","end":"2026-05-07T03:25:41.650552Z","steps":["trace[795511205] 'read index received' (duration: 144.829038ms)","trace[795511205] 'applied index is now lower than readState.Index' (duration: 210.963272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:41.650679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.920602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:41.650659Z","caller":"traceutil/trace.go:171","msg":"trace[1290189681] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"476.30716ms","start":"2026-05-07T03:25:41.174309Z","end":"2026-05-07T03:25:41.650616Z","steps":["trace[1290189681] 'process raft request' (duration: 265.342768ms)","trace[1290189681] 'compare' (duration: 210.46835ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:41.650716Z","caller":"traceutil/trace.go:171","msg":"trace[349738731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"355.976024ms","start":"2026-05-07T03:25:41.294727Z","end":"2026-05-07T03:25:41.650703Z","steps":["trace[349738731] 'agreement among raft nodes before linearized reading' (duration: 355.918003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:41.650819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:41.294715Z","time spent":"356.091197ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:25:41.651046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:41.17429Z","time spent":"476.429133ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:41.651155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.856638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T03:25:41.651171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.588828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:25:41.651236Z","caller":"traceutil/trace.go:171","msg":"trace[1962221007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"177.972842ms","start":"2026-05-07T03:25:41.473243Z","end":"2026-05-07T03:25:41.651215Z","steps":["trace[1962221007] 'agreement among raft nodes before linearized reading' (duration: 177.750626ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:41.651247Z","caller":"traceutil/trace.go:171","msg":"trace[1738756816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1207; }","duration":"233.738311ms","start":"2026-05-07T03:25:41.417488Z","end":"2026-05-07T03:25:41.651227Z","steps":["trace[1738756816] 'agreement among raft nodes before linearized reading' (duration: 233.493065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:41.932968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.059205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:25:41.933039Z","caller":"traceutil/trace.go:171","msg":"trace[875755606] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1209; }","duration":"128.142317ms","start":"2026-05-07T03:25:41.80488Z","end":"2026-05-07T03:25:41.933022Z","steps":["trace[875755606] 'range keys from in-memory index tree' (duration: 127.857731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:42.835807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.35073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:25:42.835872Z","caller":"traceutil/trace.go:171","msg":"trace[1124459159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1214; }","duration":"248.460963ms","start":"2026-05-07T03:25:42.587397Z","end":"2026-05-07T03:25:42.835858Z","steps":["trace[1124459159] 'range keys from in-memory index tree' (duration: 248.213137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:42.83611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.490393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:42.836191Z","caller":"traceutil/trace.go:171","msg":"trace[509010617] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1214; }","duration":"331.658857ms","start":"2026-05-07T03:25:42.504513Z","end":"2026-05-07T03:25:42.836172Z","steps":["trace[509010617] 'range keys from in-memory index tree' (duration: 331.423561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:42.836269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:42.504496Z","time spent":"331.762129ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-07T03:25:42.999391Z","caller":"traceutil/trace.go:171","msg":"trace[998317960] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"158.917164ms","start":"2026-05-07T03:25:42.840456Z","end":"2026-05-07T03:25:42.999373Z","steps":["trace[998317960] 'process raft request' (duration: 158.803732ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:43.331568Z","caller":"traceutil/trace.go:171","msg":"trace[2114943995] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"490.858568ms","start":"2026-05-07T03:25:42.840654Z","end":"2026-05-07T03:25:43.331513Z","steps":["trace[2114943995] 'process raft request' (duration: 490.749105ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:43.331596Z","caller":"traceutil/trace.go:171","msg":"trace[13428620] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"491.003741ms","start":"2026-05-07T03:25:42.840559Z","end":"2026-05-07T03:25:43.331562Z","steps":["trace[13428620] 'process raft request' (duration: 462.422899ms)","trace[13428620] 'compare' (duration: 28.053589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:43.331732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:42.840632Z","time spent":"491.020491ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:43.331916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:42.84054Z","time spent":"491.124534ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:25:43.787371Z","caller":"traceutil/trace.go:171","msg":"trace[698885304] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"113.616901ms","start":"2026-05-07T03:25:43.673721Z","end":"2026-05-07T03:25:43.787338Z","steps":["trace[698885304] 'process raft request' (duration: 113.406216ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:43.89443Z","caller":"traceutil/trace.go:171","msg":"trace[1527334106] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"103.070443ms","start":"2026-05-07T03:25:43.791325Z","end":"2026-05-07T03:25:43.894395Z","steps":["trace[1527334106] 'process raft request' (duration: 75.026434ms)","trace[1527334106] 'compare' (duration: 27.850234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:44.764895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.508829ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:44.765005Z","caller":"traceutil/trace.go:171","msg":"trace[772272370] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1223; }","duration":"652.630592ms","start":"2026-05-07T03:25:44.11236Z","end":"2026-05-07T03:25:44.764991Z","steps":["trace[772272370] 'range keys from in-memory index tree' (duration: 652.494109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:44.765014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.260589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:44.765104Z","caller":"traceutil/trace.go:171","msg":"trace[1279218880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"467.368772ms","start":"2026-05-07T03:25:44.297698Z","end":"2026-05-07T03:25:44.765067Z","steps":["trace[1279218880] 'range keys from in-memory index tree' (duration: 467.172186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:44.765152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:44.297682Z","time spent":"467.461273ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:25:44.765273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.494163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:44.765363Z","caller":"traceutil/trace.go:171","msg":"trace[1960147978] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"407.979062ms","start":"2026-05-07T03:25:44.357367Z","end":"2026-05-07T03:25:44.765346Z","steps":["trace[1960147978] 'read index received' (duration: 306.399847ms)","trace[1960147978] 'applied index is now lower than readState.Index' (duration: 101.578085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:44.765509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.144306ms","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-05-07T03:25:44.765537Z","caller":"traceutil/trace.go:171","msg":"trace[1862636130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"408.205838ms","start":"2026-05-07T03:25:44.357322Z","end":"2026-05-07T03:25:44.765528Z","steps":["trace[1862636130] 'agreement among raft nodes before linearized reading' (duration: 408.068554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:44.76557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:44.357302Z","time spent":"408.25972ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:25:44.765781Z","caller":"traceutil/trace.go:171","msg":"trace[1060803352] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"443.235097ms","start":"2026-05-07T03:25:44.322527Z","end":"2026-05-07T03:25:44.765762Z","steps":["trace[1060803352] 'process raft request' (duration: 341.179511ms)","trace[1060803352] 'compare' (duration: 101.403131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:44.765921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:44.322508Z","time spent":"443.32284ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:25:45.044381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.721033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:45.044489Z","caller":"traceutil/trace.go:171","msg":"trace[1590471023] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"278.543063ms","start":"2026-05-07T03:25:44.76593Z","end":"2026-05-07T03:25:45.044473Z","steps":["trace[1590471023] 'read index received' (duration: 173.706137ms)","trace[1590471023] 'applied index is now lower than readState.Index' (duration: 104.835816ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:45.044792Z","caller":"traceutil/trace.go:171","msg":"trace[1678142125] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"575.498308ms","start":"2026-05-07T03:25:44.46928Z","end":"2026-05-07T03:25:45.044779Z","steps":["trace[1678142125] 'process raft request' (duration: 470.293793ms)","trace[1678142125] 'compare' (duration: 104.563848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:45.044788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.83264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:25:45.04486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:44.46926Z","time spent":"575.567249ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:45.044882Z","caller":"traceutil/trace.go:171","msg":"trace[569628398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"278.958043ms","start":"2026-05-07T03:25:44.765902Z","end":"2026-05-07T03:25:45.04486Z","steps":["trace[569628398] 'agreement among raft nodes before linearized reading' (duration: 278.808839ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:45.048703Z","caller":"traceutil/trace.go:171","msg":"trace[1615111495] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"279.858075ms","start":"2026-05-07T03:25:44.76882Z","end":"2026-05-07T03:25:45.048678Z","steps":["trace[1615111495] 'process raft request' (duration: 279.65272ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:45.268118Z","caller":"traceutil/trace.go:171","msg":"trace[458186820] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"203.310763ms","start":"2026-05-07T03:25:45.064779Z","end":"2026-05-07T03:25:45.268089Z","steps":["trace[458186820] 'read index received' (duration: 202.818112ms)","trace[458186820] 'applied index is now lower than readState.Index' (duration: 491.091µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:45.268342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.522009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:25:45.268404Z","caller":"traceutil/trace.go:171","msg":"trace[1525218703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1227; }","duration":"203.644043ms","start":"2026-05-07T03:25:45.064743Z","end":"2026-05-07T03:25:45.268387Z","steps":["trace[1525218703] 'agreement among raft nodes before linearized reading' (duration: 203.463777ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:45.268855Z","caller":"traceutil/trace.go:171","msg":"trace[128634590] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"215.480904ms","start":"2026-05-07T03:25:45.053358Z","end":"2026-05-07T03:25:45.268839Z","steps":["trace[128634590] 'process raft request' (duration: 214.275113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:45.612437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.060841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:45.612705Z","caller":"traceutil/trace.go:171","msg":"trace[640529357] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"270.180846ms","start":"2026-05-07T03:25:45.342505Z","end":"2026-05-07T03:25:45.612685Z","steps":["trace[640529357] 'process raft request' (duration: 28.790577ms)","trace[640529357] 'compare' (duration: 240.936168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:55.817006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121558481121558,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:25:56.027536Z","caller":"traceutil/trace.go:171","msg":"trace[1936599029] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"807.765762ms","start":"2026-05-07T03:25:55.219734Z","end":"2026-05-07T03:25:56.0275Z","steps":["trace[1936599029] 'process raft request' (duration: 807.581248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:56.027778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:55.21971Z","time spent":"807.907715ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:56.038686Z","caller":"traceutil/trace.go:171","msg":"trace[309296098] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"818.092296ms","start":"2026-05-07T03:25:55.220567Z","end":"2026-05-07T03:25:56.038659Z","steps":["trace[309296098] 'process raft request' (duration: 817.884071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:56.038874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:55.220546Z","time spent":"818.24847ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:56.038898Z","caller":"traceutil/trace.go:171","msg":"trace[1567357955] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"716.393847ms","start":"2026-05-07T03:25:55.322472Z","end":"2026-05-07T03:25:56.038866Z","steps":["trace[1567357955] 'process raft request' (duration: 716.10701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:56.039051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:55.322455Z","time spent":"716.50943ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:25:56.039262Z","caller":"traceutil/trace.go:171","msg":"trace[1732101288] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1348; }","duration":"723.030401ms","start":"2026-05-07T03:25:55.316209Z","end":"2026-05-07T03:25:56.03924Z","steps":["trace[1732101288] 'read index received' (duration: 711.160429ms)","trace[1732101288] 'applied index is now lower than readState.Index' (duration: 11.867012ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:56.039429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.224356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:25:56.039472Z","caller":"traceutil/trace.go:171","msg":"trace[639436375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1300; }","duration":"723.309788ms","start":"2026-05-07T03:25:55.316149Z","end":"2026-05-07T03:25:56.039459Z","steps":["trace[639436375] 'agreement among raft nodes before linearized reading' (duration: 723.171825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:56.039505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:55.316015Z","time spent":"723.482682ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:25:56.039544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.786476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T03:25:56.039607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.748315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:25:56.039624Z","caller":"traceutil/trace.go:171","msg":"trace[1704064581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"385.917881ms","start":"2026-05-07T03:25:55.653687Z","end":"2026-05-07T03:25:56.039605Z","steps":["trace[1704064581] 'agreement among raft nodes before linearized reading' (duration: 385.681224ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:56.039655Z","caller":"traceutil/trace.go:171","msg":"trace[108906625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"203.829927ms","start":"2026-05-07T03:25:55.835813Z","end":"2026-05-07T03:25:56.039643Z","steps":["trace[108906625] 'agreement among raft nodes before linearized reading' (duration: 203.593381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:56.039673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:25:55.653667Z","time spent":"385.996982ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:28:03.011143Z","caller":"traceutil/trace.go:171","msg":"trace[1098400193] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"387.411027ms","start":"2026-05-07T03:28:02.62369Z","end":"2026-05-07T03:28:03.011101Z","steps":["trace[1098400193] 'process raft request' (duration: 386.584438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:03.0114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:02.623681Z","time spent":"387.627633ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:03.012139Z","caller":"traceutil/trace.go:171","msg":"trace[1721574807] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"387.92296ms","start":"2026-05-07T03:28:02.62416Z","end":"2026-05-07T03:28:03.012083Z","steps":["trace[1721574807] 'process raft request' (duration: 387.744385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:03.012329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:02.624126Z","time spent":"388.115505ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:05.182336Z","caller":"traceutil/trace.go:171","msg":"trace[1549115139] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"162.363758ms","start":"2026-05-07T03:28:05.019948Z","end":"2026-05-07T03:28:05.182311Z","steps":["trace[1549115139] 'process raft request' (duration: 162.199054ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:05.182479Z","caller":"traceutil/trace.go:171","msg":"trace[622206837] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"162.50337ms","start":"2026-05-07T03:28:05.019957Z","end":"2026-05-07T03:28:05.18246Z","steps":["trace[622206837] 'process raft request' (duration: 162.317736ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:05.696775Z","caller":"traceutil/trace.go:171","msg":"trace[492261532] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"394.62718ms","start":"2026-05-07T03:28:05.302121Z","end":"2026-05-07T03:28:05.696748Z","steps":["trace[492261532] 'read index received' (duration: 393.890603ms)","trace[492261532] 'applied index is now lower than readState.Index' (duration: 735.327µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:05.697511Z","caller":"traceutil/trace.go:171","msg":"trace[939936599] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"467.627039ms","start":"2026-05-07T03:28:05.229857Z","end":"2026-05-07T03:28:05.697484Z","steps":["trace[939936599] 'process raft request' (duration: 466.257566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:05.697338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.840405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:28:05.697681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:05.229836Z","time spent":"467.773453ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:05.697699Z","caller":"traceutil/trace.go:171","msg":"trace[55376099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"395.624383ms","start":"2026-05-07T03:28:05.302059Z","end":"2026-05-07T03:28:05.697683Z","steps":["trace[55376099] 'agreement among raft nodes before linearized reading' (duration: 394.803284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:05.697795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:05.302032Z","time spent":"395.697635ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:28:23.109537Z","caller":"traceutil/trace.go:171","msg":"trace[938641464] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"407.031066ms","start":"2026-05-07T03:28:22.702483Z","end":"2026-05-07T03:28:23.109514Z","steps":["trace[938641464] 'process raft request' (duration: 406.893373ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:23.109621Z","caller":"traceutil/trace.go:171","msg":"trace[665259942] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"406.662558ms","start":"2026-05-07T03:28:22.702923Z","end":"2026-05-07T03:28:23.109585Z","steps":["trace[665259942] 'process raft request' (duration: 406.526525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:23.109809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:22.702913Z","time spent":"406.77601ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:23.11021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:22.702477Z","time spent":"407.279632ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:23.109532Z","caller":"traceutil/trace.go:171","msg":"trace[1775508853] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"407.071458ms","start":"2026-05-07T03:28:22.702416Z","end":"2026-05-07T03:28:23.109487Z","steps":["trace[1775508853] 'process raft request' (duration: 406.181986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:23.110628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:22.702409Z","time spent":"408.149103ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:29:00.799275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.824142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:29:00.799811Z","caller":"traceutil/trace.go:171","msg":"trace[1333212657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2168; }","duration":"501.426306ms","start":"2026-05-07T03:29:00.298354Z","end":"2026-05-07T03:29:00.799781Z","steps":["trace[1333212657] 'range keys from in-memory index tree' (duration: 500.662968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:00.799957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:29:00.29832Z","time spent":"501.524948ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}