{"level":"warn","ts":"2026-05-29T21:51:01.937736Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:51:01.937864Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T21:51:01.937935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:51:01.937946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-29T21:51:01.937968Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:51:01.938625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-05-29T21:51:01.938703Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T21:51:01.945569Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.254131ms"} {"level":"info","ts":"2026-05-29T21:51:01.958789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-05-29T21:51:01.958985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:51:01.959024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-05-29T21:51:01.959289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:51:01.959309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-05-29T21:51:01.959389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-05-29T21:51:01.967632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:51:01.969159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:51:01.970501Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T21:51:01.973426Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:51:01.973744Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:51:01.974185Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:51:01.974376Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:51:01.974547Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:51:01.979186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-05-29T21:51:01.979667Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-29T21:51:01.980972Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:51:01.981236Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:51:01.981278Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:51:01.981433Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-29T21:51:01.981448Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-29T21:51:02.460491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:51:02.46059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:51:02.460629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-05-29T21:51:02.460651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:51:02.460664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-29T21:51:02.460837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-05-29T21:51:02.460968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-29T21:51:02.462952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:51:02.463342Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:51:02.464886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:51:02.465505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:51:02.465874Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:51:02.465905Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:51:02.466074Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:51:02.466256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:51:02.466297Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:51:02.469573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T21:51:02.477213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-05-29T21:51:08.058038Z","caller":"traceutil/trace.go:171","msg":"trace[906593349] transaction","detail":"{read_only:false; number_of_response:0; response_revision:234; }","duration":"322.039754ms","start":"2026-05-29T21:51:07.735977Z","end":"2026-05-29T21:51:08.058017Z","steps":["trace[906593349] 'process raft request' (duration: 321.90565ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:08.058159Z","caller":"traceutil/trace.go:171","msg":"trace[1083911959] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"414.774984ms","start":"2026-05-29T21:51:07.643355Z","end":"2026-05-29T21:51:08.05813Z","steps":["trace[1083911959] 'process raft request' (duration: 400.85953ms)","trace[1083911959] 'compare' (duration: 13.09253ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:08.05821Z","caller":"traceutil/trace.go:171","msg":"trace[1470618534] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:239; }","duration":"187.066177ms","start":"2026-05-29T21:51:07.871119Z","end":"2026-05-29T21:51:08.058185Z","steps":["trace[1470618534] 'read index received' (duration: 173.180445ms)","trace[1470618534] 'applied index is now lower than readState.Index' (duration: 13.883032ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:08.058266Z","caller":"traceutil/trace.go:171","msg":"trace[801587302] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"303.534594ms","start":"2026-05-29T21:51:07.754697Z","end":"2026-05-29T21:51:08.058231Z","steps":["trace[801587302] 'process raft request' (duration: 303.318648ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:08.058333Z","caller":"traceutil/trace.go:171","msg":"trace[1350600716] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"303.991557ms","start":"2026-05-29T21:51:07.754259Z","end":"2026-05-29T21:51:08.058251Z","steps":["trace[1350600716] 'process raft request' (duration: 303.662868ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:08.058399Z","caller":"traceutil/trace.go:171","msg":"trace[304957936] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"303.775962ms","start":"2026-05-29T21:51:07.754608Z","end":"2026-05-29T21:51:08.058384Z","steps":["trace[304957936] 'process raft request' (duration: 303.380061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:08.058421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.255192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:08.058043Z","caller":"traceutil/trace.go:171","msg":"trace[1233028047] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"412.985512ms","start":"2026-05-29T21:51:07.645013Z","end":"2026-05-29T21:51:08.057999Z","steps":["trace[1233028047] 'process raft request' (duration: 412.773945ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:08.058496Z","caller":"traceutil/trace.go:171","msg":"trace[351285648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:237; }","duration":"187.394156ms","start":"2026-05-29T21:51:07.871084Z","end":"2026-05-29T21:51:08.058478Z","steps":["trace[351285648] 'agreement among raft nodes before linearized reading' (duration: 187.18046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:08.058629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.735952Z","time spent":"322.184038ms","remote":"127.0.0.1:52722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:08.058723Z","caller":"traceutil/trace.go:171","msg":"trace[1224888327] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"303.910275ms","start":"2026-05-29T21:51:07.754803Z","end":"2026-05-29T21:51:08.058713Z","steps":["trace[1224888327] 'process raft request' (duration: 303.245616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:08.058785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.754231Z","time spent":"304.190513ms","remote":"127.0.0.1:52642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.058874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.754587Z","time spent":"303.841153ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.754686Z","time spent":"303.674689ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.058795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.754793Z","time spent":"303.968087ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:08.05909Z","caller":"traceutil/trace.go:171","msg":"trace[1009541032] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"304.261335ms","start":"2026-05-29T21:51:07.754816Z","end":"2026-05-29T21:51:08.059077Z","steps":["trace[1009541032] 'process raft request' (duration: 303.254037ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:08.059002Z","caller":"traceutil/trace.go:171","msg":"trace[2094532851] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"303.622657ms","start":"2026-05-29T21:51:07.755249Z","end":"2026-05-29T21:51:08.058871Z","steps":["trace[2094532851] 'process raft request' (duration: 302.866376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:08.059236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.754796Z","time spent":"304.353187ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.059311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.75521Z","time spent":"304.018408ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.059377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.643335Z","time spent":"414.882277ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:08.059324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:07.644995Z","time spent":"413.518697ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:11.301104Z","caller":"traceutil/trace.go:171","msg":"trace[578531673] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"272.704793ms","start":"2026-05-29T21:51:11.028366Z","end":"2026-05-29T21:51:11.301071Z","steps":["trace[578531673] 'read index received' (duration: 272.514038ms)","trace[578531673] 'applied index is now lower than readState.Index' (duration: 189.185µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:11.301441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.035241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T21:51:11.301562Z","caller":"traceutil/trace.go:171","msg":"trace[343849273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"273.248137ms","start":"2026-05-29T21:51:11.028288Z","end":"2026-05-29T21:51:11.301536Z","steps":["trace[343849273] 'agreement among raft nodes before linearized reading' (duration: 272.915379ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:11.302025Z","caller":"traceutil/trace.go:171","msg":"trace[1348705209] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"315.665277ms","start":"2026-05-29T21:51:10.985499Z","end":"2026-05-29T21:51:11.301165Z","steps":["trace[1348705209] 'process raft request' (duration: 315.3727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:11.302219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:10.985465Z","time spent":"316.647885ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:12.657997Z","caller":"traceutil/trace.go:171","msg":"trace[1829418194] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"173.748152ms","start":"2026-05-29T21:51:12.484225Z","end":"2026-05-29T21:51:12.657973Z","steps":["trace[1829418194] 'read index received' (duration: 173.582238ms)","trace[1829418194] 'applied index is now lower than readState.Index' (duration: 164.864µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:12.65817Z","caller":"traceutil/trace.go:171","msg":"trace[1548865516] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"194.063399ms","start":"2026-05-29T21:51:12.464047Z","end":"2026-05-29T21:51:12.65811Z","steps":["trace[1548865516] 'process raft request' (duration: 193.774891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:12.6583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.040359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T21:51:12.658425Z","caller":"traceutil/trace.go:171","msg":"trace[124841276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"174.217314ms","start":"2026-05-29T21:51:12.484173Z","end":"2026-05-29T21:51:12.65839Z","steps":["trace[124841276] 'agreement among raft nodes before linearized reading' (duration: 173.979698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:12.658341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.705869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-29T21:51:12.658665Z","caller":"traceutil/trace.go:171","msg":"trace[1046855702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"123.070269ms","start":"2026-05-29T21:51:12.535578Z","end":"2026-05-29T21:51:12.658648Z","steps":["trace[1046855702] 'agreement among raft nodes before linearized reading' (duration: 122.71331ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:12.771668Z","caller":"traceutil/trace.go:171","msg":"trace[1728012797] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"103.109793ms","start":"2026-05-29T21:51:12.668533Z","end":"2026-05-29T21:51:12.771643Z","steps":["trace[1728012797] 'process raft request' (duration: 42.575345ms)","trace[1728012797] 'compare' (duration: 60.189719ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:12.996782Z","caller":"traceutil/trace.go:171","msg":"trace[708611034] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"213.979464ms","start":"2026-05-29T21:51:12.782768Z","end":"2026-05-29T21:51:12.996748Z","steps":["trace[708611034] 'process raft request' (duration: 191.707685ms)","trace[708611034] 'compare' (duration: 22.097505ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:13.076507Z","caller":"traceutil/trace.go:171","msg":"trace[312100331] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"292.873022ms","start":"2026-05-29T21:51:12.783588Z","end":"2026-05-29T21:51:13.076461Z","steps":["trace[312100331] 'process raft request' (duration: 292.628176ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.076529Z","caller":"traceutil/trace.go:171","msg":"trace[1058431201] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"292.238575ms","start":"2026-05-29T21:51:12.784257Z","end":"2026-05-29T21:51:13.076496Z","steps":["trace[1058431201] 'process raft request' (duration: 292.106581ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.076697Z","caller":"traceutil/trace.go:171","msg":"trace[1788820754] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"292.908952ms","start":"2026-05-29T21:51:12.783756Z","end":"2026-05-29T21:51:13.076665Z","steps":["trace[1788820754] 'read index received' (duration: 190.734649ms)","trace[1788820754] 'applied index is now lower than readState.Index' (duration: 102.172213ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:13.076878Z","caller":"traceutil/trace.go:171","msg":"trace[252350629] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"292.362708ms","start":"2026-05-29T21:51:12.784504Z","end":"2026-05-29T21:51:13.076866Z","steps":["trace[252350629] 'process raft request' (duration: 291.933547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.077017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.528938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T21:51:13.077074Z","caller":"traceutil/trace.go:171","msg":"trace[349868673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:306; }","duration":"242.64045ms","start":"2026-05-29T21:51:12.834418Z","end":"2026-05-29T21:51:13.077059Z","steps":["trace[349868673] 'agreement among raft nodes before linearized reading' (duration: 242.525237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.07719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.389497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T21:51:13.077226Z","caller":"traceutil/trace.go:171","msg":"trace[1842861086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:306; }","duration":"193.421917ms","start":"2026-05-29T21:51:12.883791Z","end":"2026-05-29T21:51:13.077213Z","steps":["trace[1842861086] 'agreement among raft nodes before linearized reading' (duration: 193.366526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.07721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.415035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-29T21:51:13.077289Z","caller":"traceutil/trace.go:171","msg":"trace[1726597589] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"293.542779ms","start":"2026-05-29T21:51:12.783724Z","end":"2026-05-29T21:51:13.077267Z","steps":["trace[1726597589] 'agreement among raft nodes before linearized reading' (duration: 293.359964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.077424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.682012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-29T21:51:13.077641Z","caller":"traceutil/trace.go:171","msg":"trace[357368201] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:306; }","duration":"142.906268ms","start":"2026-05-29T21:51:12.934649Z","end":"2026-05-29T21:51:13.077555Z","steps":["trace[357368201] 'agreement among raft nodes before linearized reading' (duration: 142.489886ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.215516Z","caller":"traceutil/trace.go:171","msg":"trace[751698800] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"132.476721ms","start":"2026-05-29T21:51:13.082974Z","end":"2026-05-29T21:51:13.215451Z","steps":["trace[751698800] 'process raft request' (duration: 66.384615ms)","trace[751698800] 'compare' (duration: 65.789608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:13.215577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.900338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-29T21:51:13.215826Z","caller":"traceutil/trace.go:171","msg":"trace[1959127843] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"130.822855ms","start":"2026-05-29T21:51:13.084661Z","end":"2026-05-29T21:51:13.215484Z","steps":["trace[1959127843] 'read index received' (duration: 64.681458ms)","trace[1959127843] 'applied index is now lower than readState.Index' (duration: 66.140357ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:13.21577Z","caller":"traceutil/trace.go:171","msg":"trace[157226766] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"126.497351ms","start":"2026-05-29T21:51:13.088979Z","end":"2026-05-29T21:51:13.215477Z","steps":["trace[157226766] 'process raft request' (duration: 126.427599ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.216064Z","caller":"traceutil/trace.go:171","msg":"trace[656430210] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:308; }","duration":"131.232876ms","start":"2026-05-29T21:51:13.084623Z","end":"2026-05-29T21:51:13.215856Z","steps":["trace[656430210] 'agreement among raft nodes before linearized reading' (duration: 130.864607ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.432854Z","caller":"traceutil/trace.go:171","msg":"trace[1448366325] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"207.35836ms","start":"2026-05-29T21:51:13.225455Z","end":"2026-05-29T21:51:13.432813Z","steps":["trace[1448366325] 'process raft request' (duration: 156.966351ms)","trace[1448366325] 'compare' (duration: 49.764373ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:13.432885Z","caller":"traceutil/trace.go:171","msg":"trace[1983010359] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"205.197541ms","start":"2026-05-29T21:51:13.227667Z","end":"2026-05-29T21:51:13.432864Z","steps":["trace[1983010359] 'process raft request' (duration: 205.135259ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.432922Z","caller":"traceutil/trace.go:171","msg":"trace[2102901401] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"205.89985ms","start":"2026-05-29T21:51:13.226971Z","end":"2026-05-29T21:51:13.43287Z","steps":["trace[2102901401] 'process raft request' (duration: 205.743716ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.433069Z","caller":"traceutil/trace.go:171","msg":"trace[1160273170] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:320; }","duration":"198.673399ms","start":"2026-05-29T21:51:13.234387Z","end":"2026-05-29T21:51:13.43306Z","steps":["trace[1160273170] 'read index received' (duration: 49.29455ms)","trace[1160273170] 'applied index is now lower than readState.Index' (duration: 149.377669ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:13.433123Z","caller":"traceutil/trace.go:171","msg":"trace[1061056831] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"203.98095ms","start":"2026-05-29T21:51:13.229138Z","end":"2026-05-29T21:51:13.433119Z","steps":["trace[1061056831] 'process raft request' (duration: 203.688181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.433223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.820733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T21:51:13.433248Z","caller":"traceutil/trace.go:171","msg":"trace[1210408954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:313; }","duration":"198.901445ms","start":"2026-05-29T21:51:13.23434Z","end":"2026-05-29T21:51:13.433241Z","steps":["trace[1210408954] 'agreement among raft nodes before linearized reading' (duration: 198.832763ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.442761Z","caller":"traceutil/trace.go:171","msg":"trace[1043531603] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"155.778898ms","start":"2026-05-29T21:51:13.286966Z","end":"2026-05-29T21:51:13.442745Z","steps":["trace[1043531603] 'process raft request' (duration: 155.683076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.443105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.866864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T21:51:13.443137Z","caller":"traceutil/trace.go:171","msg":"trace[1225957652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:314; }","duration":"125.942886ms","start":"2026-05-29T21:51:13.317186Z","end":"2026-05-29T21:51:13.443129Z","steps":["trace[1225957652] 'agreement among raft nodes before linearized reading' (duration: 125.864634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.443173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.112798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T21:51:13.443231Z","caller":"traceutil/trace.go:171","msg":"trace[948826283] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"159.19063ms","start":"2026-05-29T21:51:13.284027Z","end":"2026-05-29T21:51:13.443217Z","steps":["trace[948826283] 'agreement among raft nodes before linearized reading' (duration: 159.064236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.443185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.448512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T21:51:13.443296Z","caller":"traceutil/trace.go:171","msg":"trace[642066093] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"108.607756ms","start":"2026-05-29T21:51:13.334664Z","end":"2026-05-29T21:51:13.443272Z","steps":["trace[642066093] 'agreement among raft nodes before linearized reading' (duration: 108.37811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.760942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.73943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:13.761237Z","caller":"traceutil/trace.go:171","msg":"trace[1221723130] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"320.128556ms","start":"2026-05-29T21:51:13.441064Z","end":"2026-05-29T21:51:13.761192Z","steps":["trace[1221723130] 'process raft request' (duration: 319.977152ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.761243Z","caller":"traceutil/trace.go:171","msg":"trace[340262552] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"320.167457ms","start":"2026-05-29T21:51:13.441027Z","end":"2026-05-29T21:51:13.761194Z","steps":["trace[340262552] 'process raft request' (duration: 158.902152ms)","trace[340262552] 'compare' (duration: 160.198657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:13.761449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.441013Z","time spent":"320.340311ms","remote":"127.0.0.1:52642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:13.761334Z","caller":"traceutil/trace.go:171","msg":"trace[1107046142] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"315.551324ms","start":"2026-05-29T21:51:13.445753Z","end":"2026-05-29T21:51:13.761304Z","steps":["trace[1107046142] 'process raft request' (duration: 315.38646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.761684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.441038Z","time spent":"320.298141ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:51:13.76184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.445745Z","time spent":"315.909523ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:51:13.761883Z","caller":"traceutil/trace.go:171","msg":"trace[907898236] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"316.081468ms","start":"2026-05-29T21:51:13.445792Z","end":"2026-05-29T21:51:13.761873Z","steps":["trace[907898236] 'process raft request' (duration: 315.37956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.761916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.445782Z","time spent":"316.118648ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:13.761998Z","caller":"traceutil/trace.go:171","msg":"trace[711475767] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"315.733418ms","start":"2026-05-29T21:51:13.446247Z","end":"2026-05-29T21:51:13.761981Z","steps":["trace[711475767] 'process raft request' (duration: 314.967719ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.762032Z","caller":"traceutil/trace.go:171","msg":"trace[934725580] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:326; }","duration":"319.103067ms","start":"2026-05-29T21:51:13.442916Z","end":"2026-05-29T21:51:13.762019Z","steps":["trace[934725580] 'read index received' (duration: 156.97536ms)","trace[934725580] 'applied index is now lower than readState.Index' (duration: 162.126457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:13.762092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.44624Z","time spent":"315.79811ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:51:13.762104Z","caller":"traceutil/trace.go:171","msg":"trace[1998214779] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"320.183477ms","start":"2026-05-29T21:51:13.441911Z","end":"2026-05-29T21:51:13.762095Z","steps":["trace[1998214779] 'process raft request' (duration: 319.190071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.762181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.441903Z","time spent":"320.240329ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:51:13.762838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.564366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-29T21:51:13.762889Z","caller":"traceutil/trace.go:171","msg":"trace[409333127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:321; }","duration":"326.614258ms","start":"2026-05-29T21:51:13.436262Z","end":"2026-05-29T21:51:13.762876Z","steps":["trace[409333127] 'agreement among raft nodes before linearized reading' (duration: 325.801257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.762955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.436209Z","time spent":"326.738931ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-05-29T21:51:13.763093Z","caller":"traceutil/trace.go:171","msg":"trace[1341730686] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"314.821825ms","start":"2026-05-29T21:51:13.448229Z","end":"2026-05-29T21:51:13.763051Z","steps":["trace[1341730686] 'process raft request' (duration: 313.011237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.763282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.448222Z","time spent":"314.973069ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:13.763707Z","caller":"traceutil/trace.go:171","msg":"trace[1347081987] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"314.465246ms","start":"2026-05-29T21:51:13.449225Z","end":"2026-05-29T21:51:13.763691Z","steps":["trace[1347081987] 'process raft request' (duration: 313.354976ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:13.763742Z","caller":"traceutil/trace.go:171","msg":"trace[1582344704] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"314.156987ms","start":"2026-05-29T21:51:13.449559Z","end":"2026-05-29T21:51:13.763716Z","steps":["trace[1582344704] 'process raft request' (duration: 313.972183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:13.763806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.449219Z","time spent":"314.554748ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:13.763828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:13.449554Z","time spent":"314.23816ms","remote":"127.0.0.1:53032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:14.067086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.879612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:14.067594Z","caller":"traceutil/trace.go:171","msg":"trace[1348382511] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"219.83331ms","start":"2026-05-29T21:51:13.847725Z","end":"2026-05-29T21:51:14.067558Z","steps":["trace[1348382511] 'process raft request' (duration: 219.595484ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.06781Z","caller":"traceutil/trace.go:171","msg":"trace[590800404] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"219.742359ms","start":"2026-05-29T21:51:13.848018Z","end":"2026-05-29T21:51:14.06776Z","steps":["trace[590800404] 'process raft request' (duration: 219.40146ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.067886Z","caller":"traceutil/trace.go:171","msg":"trace[1892904782] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"219.161103ms","start":"2026-05-29T21:51:13.8487Z","end":"2026-05-29T21:51:14.067861Z","steps":["trace[1892904782] 'process raft request' (duration: 218.828255ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.067962Z","caller":"traceutil/trace.go:171","msg":"trace[1733443196] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:342; }","duration":"219.306967ms","start":"2026-05-29T21:51:13.848627Z","end":"2026-05-29T21:51:14.067934Z","steps":["trace[1733443196] 'read index received' (duration: 5.015494ms)","trace[1733443196] 'applied index is now lower than readState.Index' (duration: 214.289543ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:14.067685Z","caller":"traceutil/trace.go:171","msg":"trace[425395495] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"220.476289ms","start":"2026-05-29T21:51:13.84718Z","end":"2026-05-29T21:51:14.067656Z","steps":["trace[425395495] 'process raft request' (duration: 31.930859ms)","trace[425395495] 'compare' (duration: 187.680247ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:14.068268Z","caller":"traceutil/trace.go:171","msg":"trace[2018332735] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"211.116219ms","start":"2026-05-29T21:51:13.857129Z","end":"2026-05-29T21:51:14.068245Z","steps":["trace[2018332735] 'process raft request' (duration: 210.680908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.068312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.665696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-29T21:51:14.068362Z","caller":"traceutil/trace.go:171","msg":"trace[1914663189] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:336; }","duration":"219.754519ms","start":"2026-05-29T21:51:13.848592Z","end":"2026-05-29T21:51:14.068347Z","steps":["trace[1914663189] 'agreement among raft nodes before linearized reading' (duration: 219.509012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.068373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.051138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-v46d5\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-29T21:51:14.068491Z","caller":"traceutil/trace.go:171","msg":"trace[653549209] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-v46d5; range_end:; response_count:1; response_revision:336; }","duration":"211.200492ms","start":"2026-05-29T21:51:13.857228Z","end":"2026-05-29T21:51:14.068428Z","steps":["trace[653549209] 'agreement among raft nodes before linearized reading' (duration: 210.966826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.377271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.375734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:14.377855Z","caller":"traceutil/trace.go:171","msg":"trace[2130141306] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"232.32786ms","start":"2026-05-29T21:51:14.1455Z","end":"2026-05-29T21:51:14.377828Z","steps":["trace[2130141306] 'process raft request' (duration: 232.260588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.37819Z","caller":"traceutil/trace.go:171","msg":"trace[1044992327] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"303.19538ms","start":"2026-05-29T21:51:14.074977Z","end":"2026-05-29T21:51:14.378172Z","steps":["trace[1044992327] 'process raft request' (duration: 182.797759ms)","trace[1044992327] 'compare' (duration: 119.158029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:14.378273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.074948Z","time spent":"303.284973ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:14.37868Z","caller":"traceutil/trace.go:171","msg":"trace[1298285363] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"301.480705ms","start":"2026-05-29T21:51:14.077183Z","end":"2026-05-29T21:51:14.378664Z","steps":["trace[1298285363] 'process raft request' (duration: 300.235302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.37876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.077173Z","time spent":"301.551216ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:14.379015Z","caller":"traceutil/trace.go:171","msg":"trace[1784962635] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"301.641059ms","start":"2026-05-29T21:51:14.077357Z","end":"2026-05-29T21:51:14.378998Z","steps":["trace[1784962635] 'process raft request' (duration: 300.13917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.379093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.077341Z","time spent":"301.718351ms","remote":"127.0.0.1:52642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:14.379509Z","caller":"traceutil/trace.go:171","msg":"trace[2009306083] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"301.314531ms","start":"2026-05-29T21:51:14.078166Z","end":"2026-05-29T21:51:14.379481Z","steps":["trace[2009306083] 'process raft request' (duration: 299.388211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.379753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.07815Z","time spent":"301.440224ms","remote":"127.0.0.1:53014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:51:14.380029Z","caller":"traceutil/trace.go:171","msg":"trace[426108632] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"298.531507ms","start":"2026-05-29T21:51:14.081485Z","end":"2026-05-29T21:51:14.380016Z","steps":["trace[426108632] 'process raft request' (duration: 296.149025ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.380259Z","caller":"traceutil/trace.go:171","msg":"trace[2035106054] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"296.950396ms","start":"2026-05-29T21:51:14.08329Z","end":"2026-05-29T21:51:14.38024Z","steps":["trace[2035106054] 'process raft request' (duration: 294.406369ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.38046Z","caller":"traceutil/trace.go:171","msg":"trace[221132767] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:348; }","duration":"301.325091ms","start":"2026-05-29T21:51:14.079122Z","end":"2026-05-29T21:51:14.380447Z","steps":["trace[221132767] 'read index received' (duration: 32.600676ms)","trace[221132767] 'applied index is now lower than readState.Index' (duration: 268.722815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:14.380803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.14542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T21:51:14.380845Z","caller":"traceutil/trace.go:171","msg":"trace[855954820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:344; }","duration":"252.227442ms","start":"2026-05-29T21:51:14.128607Z","end":"2026-05-29T21:51:14.380834Z","steps":["trace[855954820] 'agreement among raft nodes before linearized reading' (duration: 251.947335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.380799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.66948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-05-29T21:51:14.38102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.732889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:51:14.381016Z","caller":"traceutil/trace.go:171","msg":"trace[880152309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:344; }","duration":"301.915376ms","start":"2026-05-29T21:51:14.079085Z","end":"2026-05-29T21:51:14.381001Z","steps":["trace[880152309] 'agreement among raft nodes before linearized reading' (duration: 301.451164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.381093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.079074Z","time spent":"302.006389ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-29T21:51:14.381054Z","caller":"traceutil/trace.go:171","msg":"trace[2105612260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:344; }","duration":"123.80647ms","start":"2026-05-29T21:51:14.257235Z","end":"2026-05-29T21:51:14.381041Z","steps":["trace[2105612260] 'agreement among raft nodes before linearized reading' (duration: 123.742379ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.607642Z","caller":"traceutil/trace.go:171","msg":"trace[1307477839] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"218.099405ms","start":"2026-05-29T21:51:14.389489Z","end":"2026-05-29T21:51:14.607589Z","steps":["trace[1307477839] 'process raft request' (duration: 141.7064ms)","trace[1307477839] 'compare' (duration: 76.090718ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:14.607803Z","caller":"traceutil/trace.go:171","msg":"trace[367206713] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"216.012792ms","start":"2026-05-29T21:51:14.391751Z","end":"2026-05-29T21:51:14.607764Z","steps":["trace[367206713] 'process raft request' (duration: 215.740064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.608145Z","caller":"traceutil/trace.go:171","msg":"trace[933860374] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"214.592945ms","start":"2026-05-29T21:51:14.393512Z","end":"2026-05-29T21:51:14.608105Z","steps":["trace[933860374] 'process raft request' (duration: 214.195863ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.60841Z","caller":"traceutil/trace.go:171","msg":"trace[1005165753] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:356; }","duration":"213.408492ms","start":"2026-05-29T21:51:14.39499Z","end":"2026-05-29T21:51:14.608399Z","steps":["trace[1005165753] 'read index received' (duration: 27.04497ms)","trace[1005165753] 'applied index is now lower than readState.Index' (duration: 186.362532ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:14.608565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.545515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T21:51:14.60858Z","caller":"traceutil/trace.go:171","msg":"trace[1143639913] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"213.538745ms","start":"2026-05-29T21:51:14.395025Z","end":"2026-05-29T21:51:14.608564Z","steps":["trace[1143639913] 'process raft request' (duration: 213.31462ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.608601Z","caller":"traceutil/trace.go:171","msg":"trace[105429135] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:350; }","duration":"213.628838ms","start":"2026-05-29T21:51:14.394961Z","end":"2026-05-29T21:51:14.60859Z","steps":["trace[105429135] 'agreement among raft nodes before linearized reading' (duration: 213.478034ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:14.608731Z","caller":"traceutil/trace.go:171","msg":"trace[1204981054] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"215.147138ms","start":"2026-05-29T21:51:14.393565Z","end":"2026-05-29T21:51:14.608712Z","steps":["trace[1204981054] 'process raft request' (duration: 214.45071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:14.961579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.757368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:14.961735Z","caller":"traceutil/trace.go:171","msg":"trace[195859699] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"345.86222ms","start":"2026-05-29T21:51:14.615849Z","end":"2026-05-29T21:51:14.961711Z","steps":["trace[195859699] 'process raft request' (duration: 202.868646ms)","trace[195859699] 'compare' (duration: 142.576473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:14.961822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:14.61582Z","time spent":"345.966123ms","remote":"127.0.0.1:53032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:51:15.22055Z","caller":"traceutil/trace.go:171","msg":"trace[1936668589] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"248.776714ms","start":"2026-05-29T21:51:14.971737Z","end":"2026-05-29T21:51:15.220514Z","steps":["trace[1936668589] 'process raft request' (duration: 242.454481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:21.991771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.818414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T21:51:21.991926Z","caller":"traceutil/trace.go:171","msg":"trace[882001856] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"421.094679ms","start":"2026-05-29T21:51:21.57081Z","end":"2026-05-29T21:51:21.991905Z","steps":["trace[882001856] 'read index received' (duration: 61.422µs)","trace[882001856] 'applied index is now lower than readState.Index' (duration: 421.031497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:21.992114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.148838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T21:51:21.992095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.258053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:51:21.992203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.675994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:51:21.992232Z","caller":"traceutil/trace.go:171","msg":"trace[2142479845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"191.761556ms","start":"2026-05-29T21:51:21.800462Z","end":"2026-05-29T21:51:21.992223Z","steps":["trace[2142479845] 'agreement among raft nodes before linearized reading' (duration: 191.718785ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:21.992217Z","caller":"traceutil/trace.go:171","msg":"trace[206051723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"421.439918ms","start":"2026-05-29T21:51:21.570744Z","end":"2026-05-29T21:51:21.992184Z","steps":["trace[206051723] 'agreement among raft nodes before linearized reading' (duration: 421.212012ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:21.992154Z","caller":"traceutil/trace.go:171","msg":"trace[1915230457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"195.19972ms","start":"2026-05-29T21:51:21.796942Z","end":"2026-05-29T21:51:21.992141Z","steps":["trace[1915230457] 'agreement among raft nodes before linearized reading' (duration: 195.087436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:21.992529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:21.570716Z","time spent":"421.759726ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:51:23.164754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.591491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:23.164904Z","caller":"traceutil/trace.go:171","msg":"trace[690450804] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"521.696761ms","start":"2026-05-29T21:51:22.643182Z","end":"2026-05-29T21:51:23.164878Z","steps":["trace[690450804] 'read index received' (duration: 340.483266ms)","trace[690450804] 'applied index is now lower than readState.Index' (duration: 181.211715ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:23.16493Z","caller":"traceutil/trace.go:171","msg":"trace[71078292] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"1.167865905s","start":"2026-05-29T21:51:21.99704Z","end":"2026-05-29T21:51:23.164906Z","steps":["trace[71078292] 'process raft request' (duration: 986.69401ms)","trace[71078292] 'compare' (duration: 180.210502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:23.165048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:21.997015Z","time spent":"1.167974648s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:51:23.165481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.768273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T21:51:23.165389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.159243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:51:23.165611Z","caller":"traceutil/trace.go:171","msg":"trace[1253752150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"522.510972ms","start":"2026-05-29T21:51:22.643068Z","end":"2026-05-29T21:51:23.165579Z","steps":["trace[1253752150] 'agreement among raft nodes before linearized reading' (duration: 522.143653ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:23.165537Z","caller":"traceutil/trace.go:171","msg":"trace[2048774375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"521.849216ms","start":"2026-05-29T21:51:22.643671Z","end":"2026-05-29T21:51:23.16552Z","steps":["trace[2048774375] 'agreement among raft nodes before linearized reading' (duration: 521.682341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:23.165746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:22.643032Z","time spent":"522.665916ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:51:23.165798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:22.643659Z","time spent":"522.089171ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:51:23.377359Z","caller":"traceutil/trace.go:171","msg":"trace[1490351507] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"206.063617ms","start":"2026-05-29T21:51:23.171257Z","end":"2026-05-29T21:51:23.37732Z","steps":["trace[1490351507] 'process raft request' (duration: 182.336267ms)","trace[1490351507] 'compare' (duration: 23.326211ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:23.377347Z","caller":"traceutil/trace.go:171","msg":"trace[1790323486] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"205.778511ms","start":"2026-05-29T21:51:23.171545Z","end":"2026-05-29T21:51:23.377324Z","steps":["trace[1790323486] 'process raft request' (duration: 205.700489ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:41.580227Z","caller":"traceutil/trace.go:171","msg":"trace[1210925822] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"113.878805ms","start":"2026-05-29T21:51:41.466324Z","end":"2026-05-29T21:51:41.580203Z","steps":["trace[1210925822] 'read index received' (duration: 113.695962ms)","trace[1210925822] 'applied index is now lower than readState.Index' (duration: 181.713µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:41.580326Z","caller":"traceutil/trace.go:171","msg":"trace[2110065694] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"114.635829ms","start":"2026-05-29T21:51:41.46566Z","end":"2026-05-29T21:51:41.580295Z","steps":["trace[2110065694] 'process raft request' (duration: 114.375274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:41.580405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.065058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T21:51:41.580438Z","caller":"traceutil/trace.go:171","msg":"trace[1767655906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"114.14357ms","start":"2026-05-29T21:51:41.466284Z","end":"2026-05-29T21:51:41.580428Z","steps":["trace[1767655906] 'agreement among raft nodes before linearized reading' (duration: 114.061689ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:44.319971Z","caller":"traceutil/trace.go:171","msg":"trace[1631010710] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"150.156586ms","start":"2026-05-29T21:51:44.169785Z","end":"2026-05-29T21:51:44.319941Z","steps":["trace[1631010710] 'process raft request' (duration: 149.999633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:45.204105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.581234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:45.20422Z","caller":"traceutil/trace.go:171","msg":"trace[519967809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"633.781917ms","start":"2026-05-29T21:51:44.57041Z","end":"2026-05-29T21:51:45.204191Z","steps":["trace[519967809] 'range keys from in-memory index tree' (duration: 633.401581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:45.204275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:44.570378Z","time spent":"633.884759ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:51:45.204389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.491061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:45.20447Z","caller":"traceutil/trace.go:171","msg":"trace[1553228150] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"204.497606ms","start":"2026-05-29T21:51:44.999958Z","end":"2026-05-29T21:51:45.204456Z","steps":["trace[1553228150] 'process raft request' (duration: 100.856243ms)","trace[1553228150] 'compare' (duration: 103.265036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:45.204107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.582759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:45.204545Z","caller":"traceutil/trace.go:171","msg":"trace[1974794893] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:496; }","duration":"822.056408ms","start":"2026-05-29T21:51:44.38247Z","end":"2026-05-29T21:51:45.204526Z","steps":["trace[1974794893] 'range keys from in-memory index tree' (duration: 821.510648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:45.204589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:44.382451Z","time spent":"822.122639ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-29T21:51:49.045266Z","caller":"traceutil/trace.go:171","msg":"trace[1719996077] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"188.597836ms","start":"2026-05-29T21:51:48.856636Z","end":"2026-05-29T21:51:49.045234Z","steps":["trace[1719996077] 'process raft request' (duration: 132.677877ms)","trace[1719996077] 'compare' (duration: 55.774776ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:49.620804Z","caller":"traceutil/trace.go:171","msg":"trace[314861688] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"298.803023ms","start":"2026-05-29T21:51:49.321962Z","end":"2026-05-29T21:51:49.620765Z","steps":["trace[314861688] 'read index received' (duration: 294.556007ms)","trace[314861688] 'applied index is now lower than readState.Index' (duration: 4.245946ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:49.621021Z","caller":"traceutil/trace.go:171","msg":"trace[407458985] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"435.326474ms","start":"2026-05-29T21:51:49.185627Z","end":"2026-05-29T21:51:49.620954Z","steps":["trace[407458985] 'process raft request' (duration: 433.813017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:49.621178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:49.185583Z","time spent":"435.511609ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:49.621289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.311493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:51:49.621349Z","caller":"traceutil/trace.go:171","msg":"trace[325729933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"299.428315ms","start":"2026-05-29T21:51:49.321902Z","end":"2026-05-29T21:51:49.62133Z","steps":["trace[325729933] 'agreement among raft nodes before linearized reading' (duration: 299.225701ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:49.892914Z","caller":"traceutil/trace.go:171","msg":"trace[284614258] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"202.275374ms","start":"2026-05-29T21:51:49.6906Z","end":"2026-05-29T21:51:49.892875Z","steps":["trace[284614258] 'process raft request' (duration: 202.214813ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:49.892962Z","caller":"traceutil/trace.go:171","msg":"trace[2136214446] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"203.108438ms","start":"2026-05-29T21:51:49.689818Z","end":"2026-05-29T21:51:49.892926Z","steps":["trace[2136214446] 'process raft request' (duration: 133.924255ms)","trace[2136214446] 'compare' (duration: 68.865978ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:50.282692Z","caller":"traceutil/trace.go:171","msg":"trace[1750587983] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"183.097089ms","start":"2026-05-29T21:51:50.099559Z","end":"2026-05-29T21:51:50.282656Z","steps":["trace[1750587983] 'process raft request' (duration: 182.207993ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:51.030246Z","caller":"traceutil/trace.go:171","msg":"trace[1436613160] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"264.677554ms","start":"2026-05-29T21:51:50.765539Z","end":"2026-05-29T21:51:51.030217Z","steps":["trace[1436613160] 'process raft request' (duration: 263.817868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:51.533469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.122193ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T21:51:51.533759Z","caller":"traceutil/trace.go:171","msg":"trace[59260611] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"441.636251ms","start":"2026-05-29T21:51:51.092102Z","end":"2026-05-29T21:51:51.533739Z","steps":["trace[59260611] 'process raft request' (duration: 441.476159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:51.533873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:51.092076Z","time spent":"441.731782ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:52.088785Z","caller":"traceutil/trace.go:171","msg":"trace[1741875948] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"186.710035ms","start":"2026-05-29T21:51:51.902043Z","end":"2026-05-29T21:51:52.088753Z","steps":["trace[1741875948] 'process raft request' (duration: 96.062577ms)","trace[1741875948] 'compare' (duration: 90.047038ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:52.168183Z","caller":"traceutil/trace.go:171","msg":"trace[619991818] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"187.395194ms","start":"2026-05-29T21:51:51.980746Z","end":"2026-05-29T21:51:52.168141Z","steps":["trace[619991818] 'process raft request' (duration: 186.694531ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:52.535874Z","caller":"traceutil/trace.go:171","msg":"trace[1855289370] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"314.399284ms","start":"2026-05-29T21:51:52.22143Z","end":"2026-05-29T21:51:52.535829Z","steps":["trace[1855289370] 'process raft request' (duration: 307.74362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:52.536129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:52.221398Z","time spent":"314.590777ms","remote":"127.0.0.1:52892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:51:53.805571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.861561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:53.805708Z","caller":"traceutil/trace.go:171","msg":"trace[786895270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"238.075634ms","start":"2026-05-29T21:51:53.567602Z","end":"2026-05-29T21:51:53.805678Z","steps":["trace[786895270] 'range keys from in-memory index tree' (duration: 237.756939ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:53.91052Z","caller":"traceutil/trace.go:171","msg":"trace[298360176] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"100.46775ms","start":"2026-05-29T21:51:53.810027Z","end":"2026-05-29T21:51:53.910494Z","steps":["trace[298360176] 'process raft request' (duration: 100.257777ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:54.071069Z","caller":"traceutil/trace.go:171","msg":"trace[765230132] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"118.322485ms","start":"2026-05-29T21:51:53.952714Z","end":"2026-05-29T21:51:54.071036Z","steps":["trace[765230132] 'process raft request' (duration: 100.141866ms)","trace[765230132] 'compare' (duration: 17.653099ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:54.080603Z","caller":"traceutil/trace.go:171","msg":"trace[752242319] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"125.477664ms","start":"2026-05-29T21:51:53.955095Z","end":"2026-05-29T21:51:54.080572Z","steps":["trace[752242319] 'process raft request' (duration: 125.332612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:54.878473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.616709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:54.878611Z","caller":"traceutil/trace.go:171","msg":"trace[1266298279] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"315.279264ms","start":"2026-05-29T21:51:54.563311Z","end":"2026-05-29T21:51:54.87859Z","steps":["trace[1266298279] 'read index received' (duration: 57.921µs)","trace[1266298279] 'applied index is now lower than readState.Index' (duration: 315.219493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:54.878846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.239221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:54.878879Z","caller":"traceutil/trace.go:171","msg":"trace[1574413627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"309.336082ms","start":"2026-05-29T21:51:54.56953Z","end":"2026-05-29T21:51:54.878866Z","steps":["trace[1574413627] 'agreement among raft nodes before linearized reading' (duration: 309.271361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:54.87894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:54.569497Z","time spent":"309.435673ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:51:54.878971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.64594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:51:54.879058Z","caller":"traceutil/trace.go:171","msg":"trace[2116421530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"315.781412ms","start":"2026-05-29T21:51:54.563253Z","end":"2026-05-29T21:51:54.879034Z","steps":["trace[2116421530] 'agreement among raft nodes before linearized reading' (duration: 315.450187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:54.879289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:54.563231Z","time spent":"315.872613ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:51:54.878867Z","caller":"traceutil/trace.go:171","msg":"trace[1362078939] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"726.536662ms","start":"2026-05-29T21:51:54.152251Z","end":"2026-05-29T21:51:54.878787Z","steps":["trace[1362078939] 'process raft request' (duration: 132.511465ms)","trace[1362078939] 'compare' (duration: 593.408736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:54.879712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:54.152223Z","time spent":"727.413107ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:51:55.353108Z","caller":"traceutil/trace.go:171","msg":"trace[1391374005] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"459.195632ms","start":"2026-05-29T21:51:54.893885Z","end":"2026-05-29T21:51:55.35308Z","steps":["trace[1391374005] 'read index received' (duration: 458.954679ms)","trace[1391374005] 'applied index is now lower than readState.Index' (duration: 239.443µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:55.35324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.335956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:55.353202Z","caller":"traceutil/trace.go:171","msg":"trace[1816047556] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"468.152045ms","start":"2026-05-29T21:51:54.88499Z","end":"2026-05-29T21:51:55.353142Z","steps":["trace[1816047556] 'process raft request' (duration: 467.930741ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:55.353285Z","caller":"traceutil/trace.go:171","msg":"trace[1758141095] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:610; }","duration":"459.414437ms","start":"2026-05-29T21:51:54.893854Z","end":"2026-05-29T21:51:55.353268Z","steps":["trace[1758141095] 'agreement among raft nodes before linearized reading' (duration: 459.333446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:55.353325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:54.893842Z","time spent":"459.474038ms","remote":"127.0.0.1:52788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-29T21:51:55.353425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:54.884966Z","time spent":"468.331788ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:51:57.570196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999981024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T21:51:57.570288Z","caller":"traceutil/trace.go:171","msg":"trace[1544086089] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000188058s","start":"2026-05-29T21:51:55.57008Z","end":"2026-05-29T21:51:57.570268Z","steps":["trace[1544086089] 'agreement among raft nodes before linearized reading' (duration: 2.000043606s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:57.570339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:55.570054Z","time spent":"2.00027508s","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:51:57.839978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917513893s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:57.84012Z","caller":"traceutil/trace.go:171","msg":"trace[2004710860] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:610; }","duration":"1.917669786s","start":"2026-05-29T21:51:55.922431Z","end":"2026-05-29T21:51:57.840101Z","steps":["trace[2004710860] 'range keys from in-memory index tree' (duration: 1.917480723s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:57.84062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.827569885s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:51:57.840764Z","caller":"traceutil/trace.go:171","msg":"trace[433191199] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"2.298666409s","start":"2026-05-29T21:51:55.542071Z","end":"2026-05-29T21:51:57.840738Z","steps":["trace[433191199] 'read index received' (duration: 470.86903ms)","trace[433191199] 'applied index is now lower than readState.Index' (duration: 1.827795269s)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:51:57.84081Z","caller":"traceutil/trace.go:171","msg":"trace[142291046] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"2.309035292s","start":"2026-05-29T21:51:55.531735Z","end":"2026-05-29T21:51:57.84077Z","steps":["trace[142291046] 'process raft request' (duration: 481.148492ms)","trace[142291046] 'compare' (duration: 1.827280361s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:57.841011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.298932153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T21:51:57.841023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:55.531697Z","time spent":"2.309201715s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:51:57.841062Z","caller":"traceutil/trace.go:171","msg":"trace[1402013412] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:611; }","duration":"2.299031405s","start":"2026-05-29T21:51:55.542022Z","end":"2026-05-29T21:51:57.841053Z","steps":["trace[1402013412] 'agreement among raft nodes before linearized reading' (duration: 2.298806721s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:57.84111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:55.542011Z","time spent":"2.299087575s","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-05-29T21:51:58.341437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765717346733087,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:51:58.66814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.653314598s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:51:58.669612Z","caller":"traceutil/trace.go:171","msg":"trace[123480515] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.921415094s","start":"2026-05-29T21:51:55.748168Z","end":"2026-05-29T21:51:58.669583Z","steps":["trace[123480515] 'process raft request' (duration: 2.920669302s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:58.669808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:55.748132Z","time spent":"2.921592067s","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:51:58.672952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:57.843045Z","time spent":"829.903672ms","remote":"127.0.0.1:52552","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T21:51:58.672962Z","caller":"traceutil/trace.go:171","msg":"trace[571703184] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"832.126138ms","start":"2026-05-29T21:51:57.840794Z","end":"2026-05-29T21:51:58.67292Z","steps":["trace[571703184] 'read index received' (duration: 827.646734ms)","trace[571703184] 'applied index is now lower than readState.Index' (duration: 4.477694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:51:58.673072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102264064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:51:58.673115Z","caller":"traceutil/trace.go:171","msg":"trace[51787233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"1.102332975s","start":"2026-05-29T21:51:57.570768Z","end":"2026-05-29T21:51:58.673101Z","steps":["trace[51787233] 'agreement among raft nodes before linearized reading' (duration: 1.102255914s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:58.67311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.757688626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T21:51:58.673121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313447829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T21:51:58.673146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:57.570753Z","time spent":"1.102387196s","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:51:58.673172Z","caller":"traceutil/trace.go:171","msg":"trace[94875143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"2.757804328s","start":"2026-05-29T21:51:55.915353Z","end":"2026-05-29T21:51:58.673157Z","steps":["trace[94875143] 'agreement among raft nodes before linearized reading' (duration: 2.757674476s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:51:58.673187Z","caller":"traceutil/trace.go:171","msg":"trace[475962812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"1.313574781s","start":"2026-05-29T21:51:57.359593Z","end":"2026-05-29T21:51:58.673168Z","steps":["trace[475962812] 'agreement among raft nodes before linearized reading' (duration: 1.313418639s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:58.673227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:55.91533Z","time spent":"2.757882299s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:51:58.673238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:57.359572Z","time spent":"1.313654992s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:51:58.6734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.546602614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T21:51:58.673429Z","caller":"traceutil/trace.go:171","msg":"trace[568675337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"2.546652134s","start":"2026-05-29T21:51:56.126767Z","end":"2026-05-29T21:51:58.673419Z","steps":["trace[568675337] 'agreement among raft nodes before linearized reading' (duration: 2.546591523s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:58.673456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:56.126755Z","time spent":"2.546695175s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:51:58.673543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.588088763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T21:51:58.67358Z","caller":"traceutil/trace.go:171","msg":"trace[443300119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"2.588172693s","start":"2026-05-29T21:51:56.085396Z","end":"2026-05-29T21:51:58.673569Z","steps":["trace[443300119] 'agreement among raft nodes before linearized reading' (duration: 2.588049841s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:51:58.673609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:51:56.085367Z","time spent":"2.588236126s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:52:02.467255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.76551105s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:52:02.46782Z","caller":"traceutil/trace.go:171","msg":"trace[943104762] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.766268632s","start":"2026-05-29T21:52:00.701519Z","end":"2026-05-29T21:52:02.467788Z","steps":["trace[943104762] 'process raft request' (duration: 1.766022338s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:02.468043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:00.701461Z","time spent":"1.766465405s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:02.629446Z","caller":"traceutil/trace.go:171","msg":"trace[735640066] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.927720733s","start":"2026-05-29T21:52:00.701695Z","end":"2026-05-29T21:52:02.629416Z","steps":["trace[735640066] 'process raft request' (duration: 1.862762705s)","trace[735640066] 'compare' (duration: 64.733005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:02.629669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:00.701684Z","time spent":"1.927860276s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:02.629699Z","caller":"traceutil/trace.go:171","msg":"trace[1600215355] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.927442461s","start":"2026-05-29T21:52:00.702216Z","end":"2026-05-29T21:52:02.629659Z","steps":["trace[1600215355] 'process raft request' (duration: 1.927150216s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:02.629922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:00.702153Z","time spent":"1.927655463s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:02.630083Z","caller":"traceutil/trace.go:171","msg":"trace[620756993] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.927721364s","start":"2026-05-29T21:52:00.702351Z","end":"2026-05-29T21:52:02.630072Z","steps":["trace[620756993] 'process raft request' (duration: 1.927266617s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:02.630143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:00.702337Z","time spent":"1.927773216s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:05.145069Z","caller":"traceutil/trace.go:171","msg":"trace[787602889] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"304.169213ms","start":"2026-05-29T21:52:04.840585Z","end":"2026-05-29T21:52:05.144754Z","steps":["trace[787602889] 'process raft request' (duration: 303.96322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:05.145227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:04.840567Z","time spent":"304.58172ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:05.210559Z","caller":"traceutil/trace.go:171","msg":"trace[78931420] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"328.55899ms","start":"2026-05-29T21:52:04.881987Z","end":"2026-05-29T21:52:05.210546Z","steps":["trace[78931420] 'read index received' (duration: 262.724666ms)","trace[78931420] 'applied index is now lower than readState.Index' (duration: 65.833494ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:05.210638Z","caller":"traceutil/trace.go:171","msg":"trace[1118825663] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"125.938831ms","start":"2026-05-29T21:52:05.084691Z","end":"2026-05-29T21:52:05.21063Z","steps":["trace[1118825663] 'process raft request' (duration: 125.82255ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.210882Z","caller":"traceutil/trace.go:171","msg":"trace[1046465183] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"368.051568ms","start":"2026-05-29T21:52:04.842785Z","end":"2026-05-29T21:52:05.210837Z","steps":["trace[1046465183] 'process raft request' (duration: 367.634882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:05.211041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.024488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:05.211131Z","caller":"traceutil/trace.go:171","msg":"trace[495148419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:664; }","duration":"329.138919ms","start":"2026-05-29T21:52:04.881967Z","end":"2026-05-29T21:52:05.211106Z","steps":["trace[495148419] 'agreement among raft nodes before linearized reading' (duration: 328.833705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:05.211178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:04.88189Z","time spent":"329.274841ms","remote":"127.0.0.1:52576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:52:05.211222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:04.842775Z","time spent":"368.227011ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:05.377981Z","caller":"traceutil/trace.go:171","msg":"trace[2046278568] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"161.836399ms","start":"2026-05-29T21:52:05.216115Z","end":"2026-05-29T21:52:05.377951Z","steps":["trace[2046278568] 'process raft request' (duration: 161.508275ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.429362Z","caller":"traceutil/trace.go:171","msg":"trace[939037146] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"209.152192ms","start":"2026-05-29T21:52:05.220185Z","end":"2026-05-29T21:52:05.429337Z","steps":["trace[939037146] 'process raft request' (duration: 208.856867ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.429565Z","caller":"traceutil/trace.go:171","msg":"trace[341645284] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"144.008868ms","start":"2026-05-29T21:52:05.285545Z","end":"2026-05-29T21:52:05.429553Z","steps":["trace[341645284] 'process raft request' (duration: 143.648273ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.545786Z","caller":"traceutil/trace.go:171","msg":"trace[144456378] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"111.862917ms","start":"2026-05-29T21:52:05.433878Z","end":"2026-05-29T21:52:05.54574Z","steps":["trace[144456378] 'read index received' (duration: 109.39286ms)","trace[144456378] 'applied index is now lower than readState.Index' (duration: 2.467857ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:05.545869Z","caller":"traceutil/trace.go:171","msg":"trace[1499453414] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"160.389456ms","start":"2026-05-29T21:52:05.385458Z","end":"2026-05-29T21:52:05.545847Z","steps":["trace[1499453414] 'process raft request' (duration: 157.797257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:05.546332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.414695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wcjbr\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-05-29T21:52:05.546423Z","caller":"traceutil/trace.go:171","msg":"trace[787889250] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wcjbr; range_end:; response_count:1; response_revision:668; }","duration":"112.576278ms","start":"2026-05-29T21:52:05.433825Z","end":"2026-05-29T21:52:05.546402Z","steps":["trace[787889250] 'agreement among raft nodes before linearized reading' (duration: 112.08228ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.561895Z","caller":"traceutil/trace.go:171","msg":"trace[1984434585] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"125.762909ms","start":"2026-05-29T21:52:05.436113Z","end":"2026-05-29T21:52:05.561876Z","steps":["trace[1984434585] 'process raft request' (duration: 125.545296ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:05.562195Z","caller":"traceutil/trace.go:171","msg":"trace[1261795342] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"124.925825ms","start":"2026-05-29T21:52:05.437243Z","end":"2026-05-29T21:52:05.562169Z","steps":["trace[1261795342] 'process raft request' (duration: 124.5542ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:07.033672Z","caller":"traceutil/trace.go:171","msg":"trace[1894270299] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"150.810086ms","start":"2026-05-29T21:52:06.882833Z","end":"2026-05-29T21:52:07.033643Z","steps":["trace[1894270299] 'read index received' (duration: 145.163541ms)","trace[1894270299] 'applied index is now lower than readState.Index' (duration: 5.644975ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:07.033719Z","caller":"traceutil/trace.go:171","msg":"trace[1306305807] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"154.952678ms","start":"2026-05-29T21:52:06.878731Z","end":"2026-05-29T21:52:07.033684Z","steps":["trace[1306305807] 'process raft request' (duration: 149.328674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:07.033947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.08685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-29T21:52:07.034003Z","caller":"traceutil/trace.go:171","msg":"trace[1792459255] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:696; }","duration":"151.216412ms","start":"2026-05-29T21:52:06.882767Z","end":"2026-05-29T21:52:07.033984Z","steps":["trace[1792459255] 'agreement among raft nodes before linearized reading' (duration: 151.000259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.071057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765717346733242,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:52:08.310778Z","caller":"traceutil/trace.go:171","msg":"trace[354071824] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"740.006214ms","start":"2026-05-29T21:52:07.570743Z","end":"2026-05-29T21:52:08.310749Z","steps":["trace[354071824] 'read index received' (duration: 739.791201ms)","trace[354071824] 'applied index is now lower than readState.Index' (duration: 213.773µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:08.31086Z","caller":"traceutil/trace.go:171","msg":"trace[336568549] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"798.128815ms","start":"2026-05-29T21:52:07.512686Z","end":"2026-05-29T21:52:08.310814Z","steps":["trace[336568549] 'process raft request' (duration: 797.846971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.311099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.512646Z","time spent":"798.356999ms","remote":"127.0.0.1:52576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:08.311127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.369469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:08.311208Z","caller":"traceutil/trace.go:171","msg":"trace[397031170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"740.508982ms","start":"2026-05-29T21:52:07.570679Z","end":"2026-05-29T21:52:08.311188Z","steps":["trace[397031170] 'agreement among raft nodes before linearized reading' (duration: 740.283108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.311257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.570651Z","time spent":"740.597543ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:52:08.37563Z","caller":"traceutil/trace.go:171","msg":"trace[348285485] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"256.134023ms","start":"2026-05-29T21:52:08.119472Z","end":"2026-05-29T21:52:08.375606Z","steps":["trace[348285485] 'process raft request' (duration: 255.697207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.375998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.603042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:08.376169Z","caller":"traceutil/trace.go:171","msg":"trace[1488366921] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:704; }","duration":"611.747304ms","start":"2026-05-29T21:52:07.764292Z","end":"2026-05-29T21:52:08.37604Z","steps":["trace[1488366921] 'agreement among raft nodes before linearized reading' (duration: 611.609272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.376221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.764259Z","time spent":"611.947927ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:52:08.377052Z","caller":"traceutil/trace.go:171","msg":"trace[1991964815] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"243.784639ms","start":"2026-05-29T21:52:08.133232Z","end":"2026-05-29T21:52:08.377016Z","steps":["trace[1991964815] 'process raft request' (duration: 242.077184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.377212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.881289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:52:08.377433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.610626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-29T21:52:08.377528Z","caller":"traceutil/trace.go:171","msg":"trace[2069035707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"694.773348ms","start":"2026-05-29T21:52:07.682732Z","end":"2026-05-29T21:52:08.377505Z","steps":["trace[2069035707] 'agreement among raft nodes before linearized reading' (duration: 694.25979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.377585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.682703Z","time spent":"694.871599ms","remote":"127.0.0.1:52576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-05-29T21:52:08.37751Z","caller":"traceutil/trace.go:171","msg":"trace[323655501] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:704; }","duration":"731.236364ms","start":"2026-05-29T21:52:07.646251Z","end":"2026-05-29T21:52:08.377488Z","steps":["trace[323655501] 'agreement among raft nodes before linearized reading' (duration: 730.852477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.37787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.646181Z","time spent":"731.60109ms","remote":"127.0.0.1:52766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:52:08.378694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.836823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-29T21:52:08.378759Z","caller":"traceutil/trace.go:171","msg":"trace[10224861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"496.909824ms","start":"2026-05-29T21:52:07.881827Z","end":"2026-05-29T21:52:08.378737Z","steps":["trace[10224861] 'agreement among raft nodes before linearized reading' (duration: 494.743641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.378825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.881819Z","time spent":"496.972354ms","remote":"127.0.0.1:52576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:52:08.549224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.366104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wcjbr\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-05-29T21:52:08.549305Z","caller":"traceutil/trace.go:171","msg":"trace[1960499358] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wcjbr; range_end:; response_count:1; response_revision:704; }","duration":"675.503886ms","start":"2026-05-29T21:52:07.873782Z","end":"2026-05-29T21:52:08.549286Z","steps":["trace[1960499358] 'agreement among raft nodes before linearized reading' (duration: 504.147572ms)","trace[1960499358] 'range keys from in-memory index tree' (duration: 170.998368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:08.549344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:07.873754Z","time spent":"675.581958ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-wcjbr\" "} {"level":"info","ts":"2026-05-29T21:52:08.562781Z","caller":"traceutil/trace.go:171","msg":"trace[408548534] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"184.830964ms","start":"2026-05-29T21:52:08.377901Z","end":"2026-05-29T21:52:08.562732Z","steps":["trace[408548534] 'process raft request' (duration: 184.533589ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:08.784029Z","caller":"traceutil/trace.go:171","msg":"trace[789380612] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"101.523868ms","start":"2026-05-29T21:52:08.68248Z","end":"2026-05-29T21:52:08.784004Z","steps":["trace[789380612] 'read index received' (duration: 62.127153ms)","trace[789380612] 'applied index is now lower than readState.Index' (duration: 39.395305ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:08.784156Z","caller":"traceutil/trace.go:171","msg":"trace[916108167] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"109.282593ms","start":"2026-05-29T21:52:08.674861Z","end":"2026-05-29T21:52:08.784144Z","steps":["trace[916108167] 'process raft request' (duration: 108.921287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:08.784429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.942233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-29T21:52:08.784527Z","caller":"traceutil/trace.go:171","msg":"trace[262894142] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:711; }","duration":"102.050375ms","start":"2026-05-29T21:52:08.682459Z","end":"2026-05-29T21:52:08.784509Z","steps":["trace[262894142] 'agreement among raft nodes before linearized reading' (duration: 101.857532ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:10.100453Z","caller":"traceutil/trace.go:171","msg":"trace[1128746829] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"189.189882ms","start":"2026-05-29T21:52:09.911229Z","end":"2026-05-29T21:52:10.100419Z","steps":["trace[1128746829] 'process raft request' (duration: 188.111927ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:15.143656Z","caller":"traceutil/trace.go:171","msg":"trace[165374224] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"126.396484ms","start":"2026-05-29T21:52:15.017227Z","end":"2026-05-29T21:52:15.143623Z","steps":["trace[165374224] 'read index received' (duration: 7.84593ms)","trace[165374224] 'applied index is now lower than readState.Index' (duration: 118.549014ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:15.14377Z","caller":"traceutil/trace.go:171","msg":"trace[161178003] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"140.180677ms","start":"2026-05-29T21:52:15.003535Z","end":"2026-05-29T21:52:15.143716Z","steps":["trace[161178003] 'process raft request' (duration: 139.919454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:15.143873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.611047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:52:15.145023Z","caller":"traceutil/trace.go:171","msg":"trace[1961497428] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:760; }","duration":"127.778503ms","start":"2026-05-29T21:52:15.017196Z","end":"2026-05-29T21:52:15.144974Z","steps":["trace[1961497428] 'agreement among raft nodes before linearized reading' (duration: 126.583036ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:16.190176Z","caller":"traceutil/trace.go:171","msg":"trace[372916698] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"170.097094ms","start":"2026-05-29T21:52:16.020035Z","end":"2026-05-29T21:52:16.190132Z","steps":["trace[372916698] 'process raft request' (duration: 77.459923ms)","trace[372916698] 'compare' (duration: 92.346858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:16.929972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.972864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T21:52:16.930067Z","caller":"traceutil/trace.go:171","msg":"trace[383101360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"108.136206ms","start":"2026-05-29T21:52:16.821909Z","end":"2026-05-29T21:52:16.930045Z","steps":["trace[383101360] 'range keys from in-memory index tree' (duration: 107.714421ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:17.610778Z","caller":"traceutil/trace.go:171","msg":"trace[1521192697] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"239.128491ms","start":"2026-05-29T21:52:17.371621Z","end":"2026-05-29T21:52:17.610749Z","steps":["trace[1521192697] 'read index received' (duration: 238.947978ms)","trace[1521192697] 'applied index is now lower than readState.Index' (duration: 179.063µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:17.610835Z","caller":"traceutil/trace.go:171","msg":"trace[924515940] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"374.807302ms","start":"2026-05-29T21:52:17.235991Z","end":"2026-05-29T21:52:17.610798Z","steps":["trace[924515940] 'process raft request' (duration: 374.593269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:17.610999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.445005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:52:17.611032Z","caller":"traceutil/trace.go:171","msg":"trace[233915502] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:770; }","duration":"110.568016ms","start":"2026-05-29T21:52:17.500454Z","end":"2026-05-29T21:52:17.611022Z","steps":["trace[233915502] 'agreement among raft nodes before linearized reading' (duration: 110.477584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:17.611036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.391245ms","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":"warn","ts":"2026-05-29T21:52:17.611085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:17.235959Z","time spent":"375.039756ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:17.611094Z","caller":"traceutil/trace.go:171","msg":"trace[1652663171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:770; }","duration":"239.524156ms","start":"2026-05-29T21:52:17.371554Z","end":"2026-05-29T21:52:17.611078Z","steps":["trace[1652663171] 'agreement among raft nodes before linearized reading' (duration: 239.321402ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:18.320857Z","caller":"traceutil/trace.go:171","msg":"trace[1376421641] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"386.273574ms","start":"2026-05-29T21:52:17.93455Z","end":"2026-05-29T21:52:18.320824Z","steps":["trace[1376421641] 'process raft request' (duration: 386.079231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:18.32108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:17.934533Z","time spent":"386.430667ms","remote":"127.0.0.1:52738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5029,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:18.468046Z","caller":"traceutil/trace.go:171","msg":"trace[992105879] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"139.930254ms","start":"2026-05-29T21:52:18.328089Z","end":"2026-05-29T21:52:18.468019Z","steps":["trace[992105879] 'process raft request' (duration: 139.70359ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:18.468197Z","caller":"traceutil/trace.go:171","msg":"trace[1257079282] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"133.387366ms","start":"2026-05-29T21:52:18.334776Z","end":"2026-05-29T21:52:18.468163Z","steps":["trace[1257079282] 'process raft request' (duration: 133.178194ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:18.629816Z","caller":"traceutil/trace.go:171","msg":"trace[560801904] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"162.906855ms","start":"2026-05-29T21:52:18.466865Z","end":"2026-05-29T21:52:18.629772Z","steps":["trace[560801904] 'process raft request' (duration: 113.691526ms)","trace[560801904] 'compare' (duration: 49.035477ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:18.636412Z","caller":"traceutil/trace.go:171","msg":"trace[1387452981] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"162.682122ms","start":"2026-05-29T21:52:18.473712Z","end":"2026-05-29T21:52:18.636394Z","steps":["trace[1387452981] 'process raft request' (duration: 162.53195ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:18.636746Z","caller":"traceutil/trace.go:171","msg":"trace[564283625] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"161.624177ms","start":"2026-05-29T21:52:18.475101Z","end":"2026-05-29T21:52:18.636725Z","steps":["trace[564283625] 'process raft request' (duration: 161.240382ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:18.636921Z","caller":"traceutil/trace.go:171","msg":"trace[129729825] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"161.597928ms","start":"2026-05-29T21:52:18.475305Z","end":"2026-05-29T21:52:18.636902Z","steps":["trace[129729825] 'process raft request' (duration: 161.261333ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:19.723093Z","caller":"traceutil/trace.go:171","msg":"trace[1540935130] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"102.693995ms","start":"2026-05-29T21:52:19.620361Z","end":"2026-05-29T21:52:19.723055Z","steps":["trace[1540935130] 'process raft request' (duration: 102.430641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:23.466649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765717346733541,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:52:23.706624Z","caller":"traceutil/trace.go:171","msg":"trace[1028326984] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"910.029044ms","start":"2026-05-29T21:52:22.79656Z","end":"2026-05-29T21:52:23.706589Z","steps":["trace[1028326984] 'process raft request' (duration: 909.766811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:23.706843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:22.796532Z","time spent":"910.190556ms","remote":"127.0.0.1:52816","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-29T21:52:23.781082Z","caller":"traceutil/trace.go:171","msg":"trace[659105251] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:829; }","duration":"814.939913ms","start":"2026-05-29T21:52:22.966125Z","end":"2026-05-29T21:52:23.781065Z","steps":["trace[659105251] 'read index received' (duration: 740.276669ms)","trace[659105251] 'applied index is now lower than readState.Index' (duration: 74.661914ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:23.781115Z","caller":"traceutil/trace.go:171","msg":"trace[1049070993] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"813.563065ms","start":"2026-05-29T21:52:22.967516Z","end":"2026-05-29T21:52:23.781079Z","steps":["trace[1049070993] 'process raft request' (duration: 813.440554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:23.7813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.166866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T21:52:23.781313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:22.96748Z","time spent":"813.717326ms","remote":"127.0.0.1:52788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:23.781348Z","caller":"traceutil/trace.go:171","msg":"trace[556705693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"815.246807ms","start":"2026-05-29T21:52:22.966089Z","end":"2026-05-29T21:52:23.781335Z","steps":["trace[556705693] 'agreement among raft nodes before linearized reading' (duration: 815.094624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:23.781384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:22.966066Z","time spent":"815.310619ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:52:23.781473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.949129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T21:52:23.781593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.290515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:52:23.781604Z","caller":"traceutil/trace.go:171","msg":"trace[469660093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"782.124861ms","start":"2026-05-29T21:52:22.99945Z","end":"2026-05-29T21:52:23.781575Z","steps":["trace[469660093] 'agreement among raft nodes before linearized reading' (duration: 781.773686ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:23.781659Z","caller":"traceutil/trace.go:171","msg":"trace[2051141849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"714.404367ms","start":"2026-05-29T21:52:23.067241Z","end":"2026-05-29T21:52:23.781645Z","steps":["trace[2051141849] 'agreement among raft nodes before linearized reading' (duration: 714.224955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:23.781678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:22.999438Z","time spent":"782.223722ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:52:23.78171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:23.067211Z","time spent":"714.488109ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T21:52:37.52724Z","caller":"traceutil/trace.go:171","msg":"trace[535755322] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"104.773579ms","start":"2026-05-29T21:52:37.42244Z","end":"2026-05-29T21:52:37.527214Z","steps":["trace[535755322] 'process raft request' (duration: 104.590787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:38.259001Z","caller":"traceutil/trace.go:171","msg":"trace[106895262] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"138.363101ms","start":"2026-05-29T21:52:38.12061Z","end":"2026-05-29T21:52:38.258973Z","steps":["trace[106895262] 'process raft request' (duration: 39.957075ms)","trace[106895262] 'compare' (duration: 98.033952ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:38.325225Z","caller":"traceutil/trace.go:171","msg":"trace[2121690299] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"156.35347ms","start":"2026-05-29T21:52:38.168826Z","end":"2026-05-29T21:52:38.325179Z","steps":["trace[2121690299] 'process raft request' (duration: 156.202639ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:41.983862Z","caller":"traceutil/trace.go:171","msg":"trace[2052465960] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"152.028492ms","start":"2026-05-29T21:52:41.83181Z","end":"2026-05-29T21:52:41.983838Z","steps":["trace[2052465960] 'process raft request' (duration: 151.85176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.425547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.959117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:52:44.42586Z","caller":"traceutil/trace.go:171","msg":"trace[576128632] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"333.13009ms","start":"2026-05-29T21:52:44.092667Z","end":"2026-05-29T21:52:44.425797Z","steps":["trace[576128632] 'process raft request' (duration: 128.796219ms)","trace[576128632] 'compare' (duration: 203.471802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:44.426137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.092639Z","time spent":"333.352862ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:44.425902Z","caller":"traceutil/trace.go:171","msg":"trace[64195893] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"332.861526ms","start":"2026-05-29T21:52:44.093015Z","end":"2026-05-29T21:52:44.425876Z","steps":["trace[64195893] 'process raft request' (duration: 332.803235ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.426238Z","caller":"traceutil/trace.go:171","msg":"trace[646459726] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"333.285051ms","start":"2026-05-29T21:52:44.092913Z","end":"2026-05-29T21:52:44.426198Z","steps":["trace[646459726] 'process raft request' (duration: 332.751515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.426405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.092903Z","time spent":"333.410723ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:52:44.426439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.092989Z","time spent":"333.310111ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:44.42651Z","caller":"traceutil/trace.go:171","msg":"trace[2134214087] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"333.507574ms","start":"2026-05-29T21:52:44.092946Z","end":"2026-05-29T21:52:44.426454Z","steps":["trace[2134214087] 'process raft request' (duration: 332.823266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.426843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.092923Z","time spent":"333.778397ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:48.719974Z","caller":"traceutil/trace.go:171","msg":"trace[1878238783] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"183.415354ms","start":"2026-05-29T21:52:48.536525Z","end":"2026-05-29T21:52:48.71994Z","steps":["trace[1878238783] 'process raft request' (duration: 183.256442ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:48.720234Z","caller":"traceutil/trace.go:171","msg":"trace[1672317093] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"264.480804ms","start":"2026-05-29T21:52:48.455709Z","end":"2026-05-29T21:52:48.720189Z","steps":["trace[1672317093] 'process raft request' (duration: 230.539466ms)","trace[1672317093] 'compare' (duration: 33.368383ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:48.720434Z","caller":"traceutil/trace.go:171","msg":"trace[1438534117] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"188.937564ms","start":"2026-05-29T21:52:48.531478Z","end":"2026-05-29T21:52:48.720415Z","steps":["trace[1438534117] 'read index received' (duration: 154.792372ms)","trace[1438534117] 'applied index is now lower than readState.Index' (duration: 34.143962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:48.720715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.149975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:52:48.720758Z","caller":"traceutil/trace.go:171","msg":"trace[1531482625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"268.250856ms","start":"2026-05-29T21:52:48.452493Z","end":"2026-05-29T21:52:48.720743Z","steps":["trace[1531482625] 'agreement among raft nodes before linearized reading' (duration: 268.046194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:48.720835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.372035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:48.720885Z","caller":"traceutil/trace.go:171","msg":"trace[546327408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"150.496846ms","start":"2026-05-29T21:52:48.570372Z","end":"2026-05-29T21:52:48.720869Z","steps":["trace[546327408] 'agreement among raft nodes before linearized reading' (duration: 150.409325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:48.720813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.803091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:52:48.721065Z","caller":"traceutil/trace.go:171","msg":"trace[777201761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"267.109844ms","start":"2026-05-29T21:52:48.453944Z","end":"2026-05-29T21:52:48.721054Z","steps":["trace[777201761] 'agreement among raft nodes before linearized reading' (duration: 266.610689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:48.721058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.16115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:52:48.721137Z","caller":"traceutil/trace.go:171","msg":"trace[892199075] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1010; }","duration":"136.321812ms","start":"2026-05-29T21:52:48.584797Z","end":"2026-05-29T21:52:48.721119Z","steps":["trace[892199075] 'agreement among raft nodes before linearized reading' (duration: 136.13376ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:52.994988Z","caller":"traceutil/trace.go:171","msg":"trace[1105427610] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"104.360035ms","start":"2026-05-29T21:53:52.890312Z","end":"2026-05-29T21:53:52.994672Z","steps":["trace[1105427610] 'process raft request' (duration: 104.152233ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:53.344753Z","caller":"traceutil/trace.go:171","msg":"trace[1955400100] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"146.275406ms","start":"2026-05-29T21:53:53.198444Z","end":"2026-05-29T21:53:53.344719Z","steps":["trace[1955400100] 'process raft request' (duration: 146.197476ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:53.344784Z","caller":"traceutil/trace.go:171","msg":"trace[732167661] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"146.78386ms","start":"2026-05-29T21:53:53.197962Z","end":"2026-05-29T21:53:53.344746Z","steps":["trace[732167661] 'process raft request' (duration: 139.837124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:11.937881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765717346734737,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:54:12.02204Z","caller":"traceutil/trace.go:171","msg":"trace[961961755] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"585.156026ms","start":"2026-05-29T21:54:11.436849Z","end":"2026-05-29T21:54:12.022005Z","steps":["trace[961961755] 'read index received' (duration: 584.787502ms)","trace[961961755] 'applied index is now lower than readState.Index' (duration: 366.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:12.022178Z","caller":"traceutil/trace.go:171","msg":"trace[1005956200] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"592.797625ms","start":"2026-05-29T21:54:11.429322Z","end":"2026-05-29T21:54:12.02212Z","steps":["trace[1005956200] 'process raft request' (duration: 592.406801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:12.02239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.629474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:12.02251Z","caller":"traceutil/trace.go:171","msg":"trace[1791750883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"452.830425ms","start":"2026-05-29T21:54:11.569647Z","end":"2026-05-29T21:54:12.022477Z","steps":["trace[1791750883] 'agreement among raft nodes before linearized reading' (duration: 452.617034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:12.022569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.569617Z","time spent":"452.942746ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:54:12.02257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.429301Z","time spent":"593.129527ms","remote":"127.0.0.1:52722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5719,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:12.022554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.782889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T21:54:12.022778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.935385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:54:12.022822Z","caller":"traceutil/trace.go:171","msg":"trace[53549678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"558.107101ms","start":"2026-05-29T21:54:11.464701Z","end":"2026-05-29T21:54:12.022808Z","steps":["trace[53549678] 'agreement among raft nodes before linearized reading' (duration: 557.677858ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:12.022836Z","caller":"traceutil/trace.go:171","msg":"trace[981916516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"552.200646ms","start":"2026-05-29T21:54:11.470621Z","end":"2026-05-29T21:54:12.022821Z","steps":["trace[981916516] 'agreement among raft nodes before linearized reading' (duration: 551.697463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:12.022868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.464674Z","time spent":"558.184052ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:54:12.022879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.470607Z","time spent":"552.258677ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:54:12.022293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.867837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T21:54:12.023067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.181154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:54:12.023189Z","caller":"traceutil/trace.go:171","msg":"trace[2018321221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"586.361525ms","start":"2026-05-29T21:54:11.43679Z","end":"2026-05-29T21:54:12.023151Z","steps":["trace[2018321221] 'agreement among raft nodes before linearized reading' (duration: 585.346728ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:12.023149Z","caller":"traceutil/trace.go:171","msg":"trace[1956718689] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1317; }","duration":"584.776282ms","start":"2026-05-29T21:54:11.438328Z","end":"2026-05-29T21:54:12.023104Z","steps":["trace[1956718689] 'agreement among raft nodes before linearized reading' (duration: 583.884016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:12.023258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.436764Z","time spent":"586.481366ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:54:12.023319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:11.438301Z","time spent":"584.960235ms","remote":"127.0.0.1:52934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:54:14.480709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.7935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-05-29T21:54:14.480811Z","caller":"traceutil/trace.go:171","msg":"trace[1541659797] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1327; }","duration":"119.963021ms","start":"2026-05-29T21:54:14.360826Z","end":"2026-05-29T21:54:14.480789Z","steps":["trace[1541659797] 'range keys from in-memory index tree' (duration: 119.572099ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:15.279064Z","caller":"traceutil/trace.go:171","msg":"trace[737180120] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"141.282858ms","start":"2026-05-29T21:54:15.137743Z","end":"2026-05-29T21:54:15.279026Z","steps":["trace[737180120] 'process raft request' (duration: 141.000946ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:15.286725Z","caller":"traceutil/trace.go:171","msg":"trace[1941304385] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"126.672429ms","start":"2026-05-29T21:54:15.160015Z","end":"2026-05-29T21:54:15.286688Z","steps":["trace[1941304385] 'process raft request' (duration: 126.506838ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:21.96305Z","caller":"traceutil/trace.go:171","msg":"trace[1097789824] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"657.251085ms","start":"2026-05-29T21:54:21.305767Z","end":"2026-05-29T21:54:21.963018Z","steps":["trace[1097789824] 'process raft request' (duration: 657.050093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:21.96324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.305735Z","time spent":"657.410657ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:21.988429Z","caller":"traceutil/trace.go:171","msg":"trace[228675684] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"680.487996ms","start":"2026-05-29T21:54:21.30791Z","end":"2026-05-29T21:54:21.988398Z","steps":["trace[228675684] 'process raft request' (duration: 680.236015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:21.98868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.30788Z","time spent":"680.654287ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:21.988536Z","caller":"traceutil/trace.go:171","msg":"trace[306264206] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"382.664496ms","start":"2026-05-29T21:54:21.605825Z","end":"2026-05-29T21:54:21.98849Z","steps":["trace[306264206] 'process raft request' (duration: 382.493875ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:21.988924Z","caller":"traceutil/trace.go:171","msg":"trace[1239753619] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"513.839366ms","start":"2026-05-29T21:54:21.475069Z","end":"2026-05-29T21:54:21.988909Z","steps":["trace[1239753619] 'read index received' (duration: 487.921945ms)","trace[1239753619] 'applied index is now lower than readState.Index' (duration: 25.915611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:21.988987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.605803Z","time spent":"383.079798ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:21.989242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.830405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:21.989099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.034707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:21.990377Z","caller":"traceutil/trace.go:171","msg":"trace[2039709413] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1356; }","duration":"515.345196ms","start":"2026-05-29T21:54:21.475004Z","end":"2026-05-29T21:54:21.990349Z","steps":["trace[2039709413] 'agreement among raft nodes before linearized reading' (duration: 513.992686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:21.990718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.474983Z","time spent":"515.481857ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:54:21.990283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.665591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:21.990986Z","caller":"traceutil/trace.go:171","msg":"trace[573914465] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1356; }","duration":"478.379493ms","start":"2026-05-29T21:54:21.512582Z","end":"2026-05-29T21:54:21.990961Z","steps":["trace[573914465] 'agreement among raft nodes before linearized reading' (duration: 476.607ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:21.990266Z","caller":"traceutil/trace.go:171","msg":"trace[1375889628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"419.879462ms","start":"2026-05-29T21:54:21.570366Z","end":"2026-05-29T21:54:21.990246Z","steps":["trace[1375889628] 'agreement among raft nodes before linearized reading' (duration: 418.820124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:21.991231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.57035Z","time spent":"420.863359ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:54:21.99105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:21.512571Z","time spent":"478.460434ms","remote":"127.0.0.1:60992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "}