{"level":"warn","ts":"2026-05-29T02:06:02.71373Z","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-29T02:06:02.713817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-29T02:06:02.71389Z","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-29T02:06:02.713905Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-29T02:06:02.713932Z","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-29T02:06:02.714665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-29T02:06:02.714767Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-29T02:06:02.718174Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.196313ms"} {"level":"info","ts":"2026-05-29T02:06:02.72351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-29T02:06:02.723617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T02:06:02.723673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-29T02:06:02.723694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T02:06:02.723708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-29T02:06:02.723769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-29T02:06:02.728563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T02:06:02.730773Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T02:06:02.731932Z","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-29T02:06:02.733994Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T02:06:02.734813Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T02:06:02.735037Z","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-29T02:06:02.739682Z","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-29T02:06:02.74052Z","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-29T02:06:02.742304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-29T02:06:02.742491Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-29T02:06:02.743731Z","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-29T02:06:02.743826Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-29T02:06:02.74396Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-29T02:06:02.74428Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T02:06:02.744395Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T02:06:03.324635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T02:06:03.324703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T02:06:03.324745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-29T02:06:03.324777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-29T02:06:03.324804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-29T02:06:03.324827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-29T02:06:03.324858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-29T02:06:03.326039Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:06:03.326904Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T02:06:03.327064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:06:03.327259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:06:03.327568Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:06:03.327722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:06:03.327775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:06:03.330781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-29T02:06:03.330926Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T02:06:03.330953Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T02:06:03.331509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T02:06:08.894529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.980046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:06:08.894669Z","caller":"traceutil/trace.go:171","msg":"trace[1001657911] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:259; }","duration":"123.163291ms","start":"2026-05-29T02:06:08.771479Z","end":"2026-05-29T02:06:08.894642Z","steps":["trace[1001657911] 'range keys from in-memory index tree' (duration: 122.856912ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.090571Z","caller":"traceutil/trace.go:171","msg":"trace[521673650] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"189.968653ms","start":"2026-05-29T02:06:08.900577Z","end":"2026-05-29T02:06:09.090546Z","steps":["trace[521673650] 'process raft request' (duration: 189.829188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.090924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.068207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:06:09.091012Z","caller":"traceutil/trace.go:171","msg":"trace[1641762098] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:260; }","duration":"164.206291ms","start":"2026-05-29T02:06:08.926786Z","end":"2026-05-29T02:06:09.090992Z","steps":["trace[1641762098] 'agreement among raft nodes before linearized reading' (duration: 163.957624ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.090998Z","caller":"traceutil/trace.go:171","msg":"trace[49574247] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:269; }","duration":"163.732059ms","start":"2026-05-29T02:06:08.92683Z","end":"2026-05-29T02:06:09.090562Z","steps":["trace[49574247] 'read index received' (duration: 163.715979ms)","trace[49574247] 'applied index is now lower than readState.Index' (duration: 12.83µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:09.592189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879388996265323,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:06:09.629175Z","caller":"traceutil/trace.go:171","msg":"trace[706594605] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"698.106217ms","start":"2026-05-29T02:06:08.931049Z","end":"2026-05-29T02:06:09.629155Z","steps":["trace[706594605] 'process raft request' (duration: 698.059786ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.629544Z","caller":"traceutil/trace.go:171","msg":"trace[684615473] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"699.382701ms","start":"2026-05-29T02:06:08.930126Z","end":"2026-05-29T02:06:09.629508Z","steps":["trace[684615473] 'process raft request' (duration: 684.169305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.6297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.930113Z","time spent":"699.501725ms","remote":"127.0.0.1:41828","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-29T02:06:09.62971Z","caller":"traceutil/trace.go:171","msg":"trace[1083300376] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"697.501373ms","start":"2026-05-29T02:06:08.932181Z","end":"2026-05-29T02:06:09.629682Z","steps":["trace[1083300376] 'process raft request' (duration: 696.950189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.629838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.932172Z","time spent":"697.609546ms","remote":"127.0.0.1:41828","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-29T02:06:09.629637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.931041Z","time spent":"698.19201ms","remote":"127.0.0.1:41828","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-29T02:06:09.630392Z","caller":"traceutil/trace.go:171","msg":"trace[1961870073] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"698.153659ms","start":"2026-05-29T02:06:08.932228Z","end":"2026-05-29T02:06:09.630382Z","steps":["trace[1961870073] 'process raft request' (duration: 697.767829ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.63042Z","caller":"traceutil/trace.go:171","msg":"trace[274474459] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"691.235219ms","start":"2026-05-29T02:06:08.939165Z","end":"2026-05-29T02:06:09.6304Z","steps":["trace[274474459] 'process raft request' (duration: 690.893169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.630486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.932224Z","time spent":"698.232111ms","remote":"127.0.0.1:41828","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-29T02:06:09.630567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.69745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T02:06:09.630646Z","caller":"traceutil/trace.go:171","msg":"trace[322364258] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"658.823263ms","start":"2026-05-29T02:06:08.971809Z","end":"2026-05-29T02:06:09.630632Z","steps":["trace[322364258] 'agreement among raft nodes before linearized reading' (duration: 658.69635ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.63065Z","caller":"traceutil/trace.go:171","msg":"trace[2073069630] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:269; }","duration":"538.994279ms","start":"2026-05-29T02:06:09.091219Z","end":"2026-05-29T02:06:09.630214Z","steps":["trace[2073069630] 'read index received' (duration: 522.935582ms)","trace[2073069630] 'applied index is now lower than readState.Index' (duration: 16.057867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:09.630689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.971788Z","time spent":"658.892866ms","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T02:06:09.630706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.939147Z","time spent":"691.346312ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:06:09.630722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.717016ms","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-29T02:06:09.630776Z","caller":"traceutil/trace.go:171","msg":"trace[586324422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:261; }","duration":"672.810619ms","start":"2026-05-29T02:06:08.957951Z","end":"2026-05-29T02:06:09.630762Z","steps":["trace[586324422] 'agreement among raft nodes before linearized reading' (duration: 672.715656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.630819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:08.957936Z","time spent":"672.87706ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T02:06:09.630822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.553766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:06:09.630859Z","caller":"traceutil/trace.go:171","msg":"trace[134666017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:261; }","duration":"536.626068ms","start":"2026-05-29T02:06:09.094222Z","end":"2026-05-29T02:06:09.630848Z","steps":["trace[134666017] 'agreement among raft nodes before linearized reading' (duration: 536.555356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.630851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.901893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T02:06:09.630908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:09.094203Z","time spent":"536.694319ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-05-29T02:06:09.630923Z","caller":"traceutil/trace.go:171","msg":"trace[694705793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:261; }","duration":"535.051067ms","start":"2026-05-29T02:06:09.095854Z","end":"2026-05-29T02:06:09.630905Z","steps":["trace[694705793] 'agreement among raft nodes before linearized reading' (duration: 534.937744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:09.63097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:09.095827Z","time spent":"535.13197ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T02:06:09.856847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.410494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:06:09.857063Z","caller":"traceutil/trace.go:171","msg":"trace[1759923526] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"149.281541ms","start":"2026-05-29T02:06:09.707766Z","end":"2026-05-29T02:06:09.857047Z","steps":["trace[1759923526] 'process raft request' (duration: 149.203359ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:09.85704Z","caller":"traceutil/trace.go:171","msg":"trace[299364428] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"151.408466ms","start":"2026-05-29T02:06:09.705593Z","end":"2026-05-29T02:06:09.857001Z","steps":["trace[299364428] 'process raft request' (duration: 26.652924ms)","trace[299364428] 'compare' (duration: 124.26826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:10.610537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.451736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:06:10.610721Z","caller":"traceutil/trace.go:171","msg":"trace[333467869] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"742.754872ms","start":"2026-05-29T02:06:09.867951Z","end":"2026-05-29T02:06:10.610706Z","steps":["trace[333467869] 'process raft request' (duration: 148.012138ms)","trace[333467869] 'compare' (duration: 594.298232ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:10.610769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:09.867933Z","time spent":"742.814354ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:06:10.610874Z","caller":"traceutil/trace.go:171","msg":"trace[556107698] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"741.726774ms","start":"2026-05-29T02:06:09.869111Z","end":"2026-05-29T02:06:10.610838Z","steps":["trace[556107698] 'process raft request' (duration: 741.519519ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:10.610751Z","caller":"traceutil/trace.go:171","msg":"trace[2090996707] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:282; }","duration":"650.385453ms","start":"2026-05-29T02:06:09.960352Z","end":"2026-05-29T02:06:10.610738Z","steps":["trace[2090996707] 'read index received' (duration: 55.581588ms)","trace[2090996707] 'applied index is now lower than readState.Index' (duration: 594.802955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:10.610986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.6356ms","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-29T02:06:10.611009Z","caller":"traceutil/trace.go:171","msg":"trace[713486195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:271; }","duration":"650.702262ms","start":"2026-05-29T02:06:09.9603Z","end":"2026-05-29T02:06:10.611002Z","steps":["trace[713486195] 'agreement among raft nodes before linearized reading' (duration: 650.652481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:10.611029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:09.960274Z","time spent":"650.751203ms","remote":"127.0.0.1:41898","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-29T02:06:10.611021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:09.869096Z","time spent":"741.859428ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:06:10.611061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.49376ms","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-29T02:06:10.611105Z","caller":"traceutil/trace.go:171","msg":"trace[715706890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:271; }","duration":"331.579443ms","start":"2026-05-29T02:06:10.279513Z","end":"2026-05-29T02:06:10.611093Z","steps":["trace[715706890] 'agreement among raft nodes before linearized reading' (duration: 331.509071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:10.611142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:10.27949Z","time spent":"331.642575ms","remote":"127.0.0.1:41898","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-29T02:06:11.097403Z","caller":"traceutil/trace.go:171","msg":"trace[347999777] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"480.716862ms","start":"2026-05-29T02:06:10.616661Z","end":"2026-05-29T02:06:11.097378Z","steps":["trace[347999777] 'process raft request' (duration: 480.674171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:11.097575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:10.616649Z","time spent":"480.852795ms","remote":"127.0.0.1:41898","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-29T02:06:11.097667Z","caller":"traceutil/trace.go:171","msg":"trace[1953820222] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"482.565799ms","start":"2026-05-29T02:06:10.615048Z","end":"2026-05-29T02:06:11.097614Z","steps":["trace[1953820222] 'process raft request' (duration: 476.998815ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:11.097819Z","caller":"traceutil/trace.go:171","msg":"trace[245178893] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"482.645172ms","start":"2026-05-29T02:06:10.615142Z","end":"2026-05-29T02:06:11.097787Z","steps":["trace[245178893] 'read index received' (duration: 477.027256ms)","trace[245178893] 'applied index is now lower than readState.Index' (duration: 5.614536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:11.097865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:10.615027Z","time spent":"482.732264ms","remote":"127.0.0.1:41898","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-29T02:06:11.098057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.297016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T02:06:11.098105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.925109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-29T02:06:11.098124Z","caller":"traceutil/trace.go:171","msg":"trace[1652217476] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:273; }","duration":"481.392088ms","start":"2026-05-29T02:06:10.616716Z","end":"2026-05-29T02:06:11.098108Z","steps":["trace[1652217476] 'agreement among raft nodes before linearized reading' (duration: 481.253025ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:11.098147Z","caller":"traceutil/trace.go:171","msg":"trace[1270876611] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:273; }","duration":"483.031332ms","start":"2026-05-29T02:06:10.615104Z","end":"2026-05-29T02:06:11.098136Z","steps":["trace[1270876611] 'agreement among raft nodes before linearized reading' (duration: 482.838227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:11.098163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:10.6167Z","time spent":"481.45504ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-29T02:06:11.098193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:10.615085Z","time spent":"483.099233ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-29T02:06:36.478559Z","caller":"traceutil/trace.go:171","msg":"trace[773113255] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"132.235493ms","start":"2026-05-29T02:06:36.3463Z","end":"2026-05-29T02:06:36.478535Z","steps":["trace[773113255] 'process raft request' (duration: 131.855323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:40.036634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.942232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:06:40.036815Z","caller":"traceutil/trace.go:171","msg":"trace[124067873] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"291.024502ms","start":"2026-05-29T02:06:39.74576Z","end":"2026-05-29T02:06:40.036785Z","steps":["trace[124067873] 'process raft request' (duration: 50.861243ms)","trace[124067873] 'compare' (duration: 239.709487ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:06:40.037212Z","caller":"traceutil/trace.go:171","msg":"trace[1430177595] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"284.357349ms","start":"2026-05-29T02:06:39.752823Z","end":"2026-05-29T02:06:40.03718Z","steps":["trace[1430177595] 'process raft request' (duration: 284.143454ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.037562Z","caller":"traceutil/trace.go:171","msg":"trace[1441905857] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"261.998963ms","start":"2026-05-29T02:06:39.775551Z","end":"2026-05-29T02:06:40.03755Z","steps":["trace[1441905857] 'process raft request' (duration: 261.958592ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.037741Z","caller":"traceutil/trace.go:171","msg":"trace[505169699] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"280.750347ms","start":"2026-05-29T02:06:39.756982Z","end":"2026-05-29T02:06:40.037733Z","steps":["trace[505169699] 'process raft request' (duration: 280.033998ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.037874Z","caller":"traceutil/trace.go:171","msg":"trace[1974948723] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"280.636044ms","start":"2026-05-29T02:06:39.75723Z","end":"2026-05-29T02:06:40.037866Z","steps":["trace[1974948723] 'process raft request' (duration: 279.855574ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.03796Z","caller":"traceutil/trace.go:171","msg":"trace[722962039] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"280.552491ms","start":"2026-05-29T02:06:39.7574Z","end":"2026-05-29T02:06:40.037952Z","steps":["trace[722962039] 'process raft request' (duration: 279.72578ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038027Z","caller":"traceutil/trace.go:171","msg":"trace[1832413726] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"280.609643ms","start":"2026-05-29T02:06:39.757409Z","end":"2026-05-29T02:06:40.038018Z","steps":["trace[1832413726] 'process raft request' (duration: 279.777822ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038082Z","caller":"traceutil/trace.go:171","msg":"trace[403470609] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"280.618562ms","start":"2026-05-29T02:06:39.757457Z","end":"2026-05-29T02:06:40.038076Z","steps":["trace[403470609] 'process raft request' (duration: 279.76501ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038139Z","caller":"traceutil/trace.go:171","msg":"trace[346133359] transaction","detail":"{read_only:false; number_of_response:1; response_revision:490; }","duration":"280.51525ms","start":"2026-05-29T02:06:39.757617Z","end":"2026-05-29T02:06:40.038132Z","steps":["trace[346133359] 'process raft request' (duration: 279.635457ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038187Z","caller":"traceutil/trace.go:171","msg":"trace[1592276452] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"275.622995ms","start":"2026-05-29T02:06:39.762557Z","end":"2026-05-29T02:06:40.03818Z","steps":["trace[1592276452] 'process raft request' (duration: 274.731471ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038268Z","caller":"traceutil/trace.go:171","msg":"trace[179337088] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"269.22039ms","start":"2026-05-29T02:06:39.769008Z","end":"2026-05-29T02:06:40.038228Z","steps":["trace[179337088] 'process raft request' (duration: 268.381488ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.038563Z","caller":"traceutil/trace.go:171","msg":"trace[151450991] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"273.91959ms","start":"2026-05-29T02:06:39.764634Z","end":"2026-05-29T02:06:40.038553Z","steps":["trace[151450991] 'process raft request' (duration: 272.713638ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.047317Z","caller":"traceutil/trace.go:171","msg":"trace[354224935] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"241.73707ms","start":"2026-05-29T02:06:39.805565Z","end":"2026-05-29T02:06:40.047302Z","steps":["trace[354224935] 'read index received' (duration: 233.175909ms)","trace[354224935] 'applied index is now lower than readState.Index' (duration: 8.560191ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:06:40.047404Z","caller":"traceutil/trace.go:171","msg":"trace[1838734972] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"244.633135ms","start":"2026-05-29T02:06:39.802763Z","end":"2026-05-29T02:06:40.047396Z","steps":["trace[1838734972] 'process raft request' (duration: 244.353248ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:40.047556Z","caller":"traceutil/trace.go:171","msg":"trace[1261607104] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"135.665122ms","start":"2026-05-29T02:06:39.911883Z","end":"2026-05-29T02:06:40.047548Z","steps":["trace[1261607104] 'process raft request' (duration: 135.361195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:40.047736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.15759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:06:40.047761Z","caller":"traceutil/trace.go:171","msg":"trace[2009392305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"242.196281ms","start":"2026-05-29T02:06:39.805557Z","end":"2026-05-29T02:06:40.047754Z","steps":["trace[2009392305] 'agreement among raft nodes before linearized reading' (duration: 242.096649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:40.055052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.668111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:06:40.055097Z","caller":"traceutil/trace.go:171","msg":"trace[27268855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"176.778853ms","start":"2026-05-29T02:06:39.878308Z","end":"2026-05-29T02:06:40.055086Z","steps":["trace[27268855] 'agreement among raft nodes before linearized reading' (duration: 176.695931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:40.055214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.046086ms","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-29T02:06:40.055231Z","caller":"traceutil/trace.go:171","msg":"trace[201515128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"135.061076ms","start":"2026-05-29T02:06:39.920163Z","end":"2026-05-29T02:06:40.055224Z","steps":["trace[201515128] 'agreement among raft nodes before linearized reading' (duration: 135.030156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:42.072544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.97905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:06:42.072652Z","caller":"traceutil/trace.go:171","msg":"trace[1763691864] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.12578936s","start":"2026-05-29T02:06:40.946845Z","end":"2026-05-29T02:06:42.072634Z","steps":["trace[1763691864] 'process raft request' (duration: 865.650135ms)","trace[1763691864] 'compare' (duration: 259.718674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:42.072706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:40.946818Z","time spent":"1.125864981s","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:06:42.078932Z","caller":"traceutil/trace.go:171","msg":"trace[199863941] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.012832173s","start":"2026-05-29T02:06:41.066071Z","end":"2026-05-29T02:06:42.078903Z","steps":["trace[199863941] 'process raft request' (duration: 1.012232329s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:42.079077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:41.066046Z","time spent":"1.012949616s","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:06:42.090453Z","caller":"traceutil/trace.go:171","msg":"trace[1974582242] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"759.763001ms","start":"2026-05-29T02:06:41.330629Z","end":"2026-05-29T02:06:42.090392Z","steps":["trace[1974582242] 'read index received' (duration: 481.821826ms)","trace[1974582242] 'applied index is now lower than readState.Index' (duration: 277.940235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:42.090508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.871794ms","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-29T02:06:42.090562Z","caller":"traceutil/trace.go:171","msg":"trace[587474986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"759.957395ms","start":"2026-05-29T02:06:41.33059Z","end":"2026-05-29T02:06:42.090547Z","steps":["trace[587474986] 'agreement among raft nodes before linearized reading' (duration: 759.854523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:42.090591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:41.330573Z","time spent":"760.013147ms","remote":"127.0.0.1:41898","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-29T02:06:42.090658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.009812ms","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-29T02:06:42.090694Z","caller":"traceutil/trace.go:171","msg":"trace[1867155081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"711.103605ms","start":"2026-05-29T02:06:41.379578Z","end":"2026-05-29T02:06:42.090682Z","steps":["trace[1867155081] 'agreement among raft nodes before linearized reading' (duration: 711.008992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:42.090725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:41.379555Z","time spent":"711.163607ms","remote":"127.0.0.1:41898","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-29T02:06:42.090883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.623859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:06:42.090936Z","caller":"traceutil/trace.go:171","msg":"trace[758848398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"212.718892ms","start":"2026-05-29T02:06:41.878205Z","end":"2026-05-29T02:06:42.090924Z","steps":["trace[758848398] 'agreement among raft nodes before linearized reading' (duration: 212.6459ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:46.13621Z","caller":"traceutil/trace.go:171","msg":"trace[1596672109] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"101.583312ms","start":"2026-05-29T02:06:46.034603Z","end":"2026-05-29T02:06:46.136186Z","steps":["trace[1596672109] 'process raft request' (duration: 101.459009ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:46.671262Z","caller":"traceutil/trace.go:171","msg":"trace[1337483629] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"340.019027ms","start":"2026-05-29T02:06:46.331209Z","end":"2026-05-29T02:06:46.671228Z","steps":["trace[1337483629] 'read index received' (duration: 325.518002ms)","trace[1337483629] 'applied index is now lower than readState.Index' (duration: 14.499745ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:06:46.671416Z","caller":"traceutil/trace.go:171","msg":"trace[1794513806] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"374.099976ms","start":"2026-05-29T02:06:46.297285Z","end":"2026-05-29T02:06:46.671385Z","steps":["trace[1794513806] 'process raft request' (duration: 359.468328ms)","trace[1794513806] 'compare' (duration: 14.231659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:06:46.671537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:46.297261Z","time spent":"374.216579ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:06:46.671492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.255072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T02:06:46.672038Z","caller":"traceutil/trace.go:171","msg":"trace[1514170252] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:588; }","duration":"340.849028ms","start":"2026-05-29T02:06:46.331173Z","end":"2026-05-29T02:06:46.672022Z","steps":["trace[1514170252] 'agreement among raft nodes before linearized reading' (duration: 340.16549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:46.672174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:46.331127Z","time spent":"340.94267ms","remote":"127.0.0.1:41686","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.132\" "} {"level":"warn","ts":"2026-05-29T02:06:48.118269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241337472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:06:48.118485Z","caller":"traceutil/trace.go:171","msg":"trace[481076787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.241713361s","start":"2026-05-29T02:06:46.876679Z","end":"2026-05-29T02:06:48.118393Z","steps":["trace[481076787] 'range keys from in-memory index tree' (duration: 1.24126188s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:48.118722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:46.876662Z","time spent":"1.242038859s","remote":"127.0.0.1:41650","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-29T02:06:48.149196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073598182s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T02:06:48.150876Z","caller":"traceutil/trace.go:171","msg":"trace[1762904251] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.076395116s","start":"2026-05-29T02:06:47.073999Z","end":"2026-05-29T02:06:48.150394Z","steps":["trace[1762904251] 'process raft request' (duration: 1.075741889s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:48.15109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:47.073967Z","time spent":"1.077045572s","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:06:49.52205Z","caller":"traceutil/trace.go:171","msg":"trace[1436096913] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"342.301806ms","start":"2026-05-29T02:06:49.179726Z","end":"2026-05-29T02:06:49.522028Z","steps":["trace[1436096913] 'process raft request' (duration: 342.209164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:49.522198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:49.17972Z","time spent":"342.398989ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:06:49.522238Z","caller":"traceutil/trace.go:171","msg":"trace[1742801428] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"342.951863ms","start":"2026-05-29T02:06:49.179263Z","end":"2026-05-29T02:06:49.522215Z","steps":["trace[1742801428] 'process raft request' (duration: 337.397589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:06:49.522326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:06:49.179256Z","time spent":"343.032825ms","remote":"127.0.0.1:41870","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-29T02:06:52.19949Z","caller":"traceutil/trace.go:171","msg":"trace[1228845181] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"139.259753ms","start":"2026-05-29T02:06:52.060207Z","end":"2026-05-29T02:06:52.199466Z","steps":["trace[1228845181] 'process raft request' (duration: 139.128ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:06:52.216648Z","caller":"traceutil/trace.go:171","msg":"trace[1293688243] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"146.876631ms","start":"2026-05-29T02:06:52.069754Z","end":"2026-05-29T02:06:52.216631Z","steps":["trace[1293688243] 'process raft request' (duration: 146.719457ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:01.60967Z","caller":"traceutil/trace.go:171","msg":"trace[858954799] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"486.220831ms","start":"2026-05-29T02:07:01.12343Z","end":"2026-05-29T02:07:01.609651Z","steps":["trace[858954799] 'process raft request' (duration: 486.088958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:01.609795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:01.123404Z","time spent":"486.329444ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:07:03.88759Z","caller":"traceutil/trace.go:171","msg":"trace[124542885] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"100.553416ms","start":"2026-05-29T02:07:03.787013Z","end":"2026-05-29T02:07:03.887567Z","steps":["trace[124542885] 'process raft request' (duration: 100.427602ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:11.305592Z","caller":"traceutil/trace.go:171","msg":"trace[1497547303] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"108.46372ms","start":"2026-05-29T02:07:11.197105Z","end":"2026-05-29T02:07:11.305569Z","steps":["trace[1497547303] 'process raft request' (duration: 108.315096ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:26.747111Z","caller":"traceutil/trace.go:171","msg":"trace[167443505] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"171.791904ms","start":"2026-05-29T02:07:26.575187Z","end":"2026-05-29T02:07:26.746979Z","steps":["trace[167443505] 'process raft request' (duration: 170.398649ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:26.749538Z","caller":"traceutil/trace.go:171","msg":"trace[1896890861] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:967; }","duration":"104.340233ms","start":"2026-05-29T02:07:26.645172Z","end":"2026-05-29T02:07:26.749513Z","steps":["trace[1896890861] 'read index received' (duration: 100.528805ms)","trace[1896890861] 'applied index is now lower than readState.Index' (duration: 3.810478ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:07:26.74965Z","caller":"traceutil/trace.go:171","msg":"trace[333404536] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"174.293369ms","start":"2026-05-29T02:07:26.575345Z","end":"2026-05-29T02:07:26.749638Z","steps":["trace[333404536] 'process raft request' (duration: 174.018062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:26.749663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.473706ms","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-29T02:07:26.749697Z","caller":"traceutil/trace.go:171","msg":"trace[1269250707] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:932; }","duration":"104.556618ms","start":"2026-05-29T02:07:26.64513Z","end":"2026-05-29T02:07:26.749686Z","steps":["trace[1269250707] 'agreement among raft nodes before linearized reading' (duration: 104.474446ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:26.749912Z","caller":"traceutil/trace.go:171","msg":"trace[277319069] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"174.312779ms","start":"2026-05-29T02:07:26.575561Z","end":"2026-05-29T02:07:26.749874Z","steps":["trace[277319069] 'process raft request' (duration: 173.911048ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:29.033241Z","caller":"traceutil/trace.go:171","msg":"trace[1926566997] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"248.205696ms","start":"2026-05-29T02:07:28.78501Z","end":"2026-05-29T02:07:29.033215Z","steps":["trace[1926566997] 'process raft request' (duration: 248.044122ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:29.064514Z","caller":"traceutil/trace.go:171","msg":"trace[1623200006] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"279.007942ms","start":"2026-05-29T02:07:28.785474Z","end":"2026-05-29T02:07:29.064482Z","steps":["trace[1623200006] 'process raft request' (duration: 278.833446ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:29.064703Z","caller":"traceutil/trace.go:171","msg":"trace[1904843196] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"279.136825ms","start":"2026-05-29T02:07:28.78553Z","end":"2026-05-29T02:07:29.064666Z","steps":["trace[1904843196] 'process raft request' (duration: 278.91331ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:29.064724Z","caller":"traceutil/trace.go:171","msg":"trace[297974736] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:973; }","duration":"188.012243ms","start":"2026-05-29T02:07:28.876678Z","end":"2026-05-29T02:07:29.06469Z","steps":["trace[297974736] 'read index received' (duration: 156.430938ms)","trace[297974736] 'applied index is now lower than readState.Index' (duration: 31.537144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:07:29.064975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.28223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:07:29.065017Z","caller":"traceutil/trace.go:171","msg":"trace[277207838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"188.374572ms","start":"2026-05-29T02:07:28.876629Z","end":"2026-05-29T02:07:29.065004Z","steps":["trace[277207838] 'agreement among raft nodes before linearized reading' (duration: 188.199818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:42.082718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.225595ms","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-29T02:07:42.082872Z","caller":"traceutil/trace.go:171","msg":"trace[845604144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"819.424831ms","start":"2026-05-29T02:07:41.263427Z","end":"2026-05-29T02:07:42.082852Z","steps":["trace[845604144] 'range keys from in-memory index tree' (duration: 819.066841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:42.082911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:41.263403Z","time spent":"819.496963ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T02:07:42.083041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.941456ms","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-29T02:07:42.083119Z","caller":"traceutil/trace.go:171","msg":"trace[285433815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"817.058368ms","start":"2026-05-29T02:07:41.266042Z","end":"2026-05-29T02:07:42.0831Z","steps":["trace[285433815] 'range keys from in-memory index tree' (duration: 816.764331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:42.083167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:41.266027Z","time spent":"817.128971ms","remote":"127.0.0.1:41898","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-29T02:07:42.569195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027600089s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T02:07:42.56984Z","caller":"traceutil/trace.go:171","msg":"trace[597307731] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1020; }","duration":"691.908057ms","start":"2026-05-29T02:07:41.877908Z","end":"2026-05-29T02:07:42.569816Z","steps":["trace[597307731] 'read index received' (duration: 51.961µs)","trace[597307731] 'applied index is now lower than readState.Index' (duration: 691.855376ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:07:42.56991Z","caller":"traceutil/trace.go:171","msg":"trace[409440631] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.045648348s","start":"2026-05-29T02:07:41.524253Z","end":"2026-05-29T02:07:42.569902Z","steps":["trace[409440631] 'process raft request' (duration: 1.045056433s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:42.569981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:41.524232Z","time spent":"1.04569575s","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:07:43.020129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142170906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:07:43.020233Z","caller":"traceutil/trace.go:171","msg":"trace[1134889892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"1.142352711s","start":"2026-05-29T02:07:41.877849Z","end":"2026-05-29T02:07:43.020202Z","steps":["trace[1134889892] 'agreement among raft nodes before linearized reading' (duration: 692.272607ms)","trace[1134889892] 'range keys from in-memory index tree' (duration: 449.901959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:07:43.020288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:41.877826Z","time spent":"1.142449123s","remote":"127.0.0.1:41650","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-29T02:07:43.020229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.423933ms","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-29T02:07:43.020394Z","caller":"traceutil/trace.go:171","msg":"trace[654635834] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:981; }","duration":"450.568117ms","start":"2026-05-29T02:07:42.569765Z","end":"2026-05-29T02:07:43.020333Z","steps":["trace[654635834] 'range keys from in-memory index tree' (duration: 450.405863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:43.020425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.485695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:07:43.020599Z","caller":"traceutil/trace.go:171","msg":"trace[470511128] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"450.70749ms","start":"2026-05-29T02:07:42.569874Z","end":"2026-05-29T02:07:43.020582Z","steps":["trace[470511128] 'read index received' (duration: 257.067µs)","trace[470511128] 'applied index is now lower than readState.Index' (duration: 450.449253ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:07:43.020681Z","caller":"traceutil/trace.go:171","msg":"trace[1898894535] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"933.654518ms","start":"2026-05-29T02:07:42.087008Z","end":"2026-05-29T02:07:43.020663Z","steps":["trace[1898894535] 'process raft request' (duration: 933.501844ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:07:43.02065Z","caller":"traceutil/trace.go:171","msg":"trace[1036542548] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"933.75091ms","start":"2026-05-29T02:07:42.086824Z","end":"2026-05-29T02:07:43.020575Z","steps":["trace[1036542548] 'process raft request' (duration: 482.987558ms)","trace[1036542548] 'compare' (duration: 450.362462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:07:43.020775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:42.086984Z","time spent":"933.73872ms","remote":"127.0.0.1:41898","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-29T02:07:43.0208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.548567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T02:07:43.020846Z","caller":"traceutil/trace.go:171","msg":"trace[695576001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"776.621409ms","start":"2026-05-29T02:07:42.244211Z","end":"2026-05-29T02:07:43.020832Z","steps":["trace[695576001] 'agreement among raft nodes before linearized reading' (duration: 776.474274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:43.020885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:42.244192Z","time spent":"776.68232ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T02:07:43.020921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.155782ms","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-29T02:07:43.020996Z","caller":"traceutil/trace.go:171","msg":"trace[1643127164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"324.365748ms","start":"2026-05-29T02:07:42.696612Z","end":"2026-05-29T02:07:43.020978Z","steps":["trace[1643127164] 'agreement among raft nodes before linearized reading' (duration: 324.118632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:07:43.02095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:42.0868Z","time spent":"933.982517ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:07:43.021061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:07:42.696593Z","time spent":"324.432289ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T02:08:05.434576Z","caller":"traceutil/trace.go:171","msg":"trace[292313861] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"289.478821ms","start":"2026-05-29T02:08:05.145068Z","end":"2026-05-29T02:08:05.434547Z","steps":["trace[292313861] 'process raft request' (duration: 289.290917ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:05.448419Z","caller":"traceutil/trace.go:171","msg":"trace[851966024] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"295.244041ms","start":"2026-05-29T02:08:05.153154Z","end":"2026-05-29T02:08:05.448398Z","steps":["trace[851966024] 'read index received' (duration: 281.384831ms)","trace[851966024] 'applied index is now lower than readState.Index' (duration: 13.85822ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:05.448454Z","caller":"traceutil/trace.go:171","msg":"trace[1837765965] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"303.342222ms","start":"2026-05-29T02:08:05.145084Z","end":"2026-05-29T02:08:05.448427Z","steps":["trace[1837765965] 'process raft request' (duration: 303.191458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:05.448565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.394605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T02:08:05.448596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:05.145046Z","time spent":"303.465605ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:08:05.448619Z","caller":"traceutil/trace.go:171","msg":"trace[1183107422] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1057; }","duration":"295.480167ms","start":"2026-05-29T02:08:05.153126Z","end":"2026-05-29T02:08:05.448606Z","steps":["trace[1183107422] 'agreement among raft nodes before linearized reading' (duration: 295.371834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:05.448673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.828937ms","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-29T02:08:05.448703Z","caller":"traceutil/trace.go:171","msg":"trace[1645434976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"215.897739ms","start":"2026-05-29T02:08:05.232797Z","end":"2026-05-29T02:08:05.448694Z","steps":["trace[1645434976] 'agreement among raft nodes before linearized reading' (duration: 215.751875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:06.375364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.058845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:06.375466Z","caller":"traceutil/trace.go:171","msg":"trace[1907677829] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1058; }","duration":"691.228158ms","start":"2026-05-29T02:08:05.684214Z","end":"2026-05-29T02:08:06.375442Z","steps":["trace[1907677829] 'count revisions from in-memory index tree' (duration: 690.982172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:06.375521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:05.684195Z","time spent":"691.309381ms","remote":"127.0.0.1:34936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:08:06.375958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.273351ms","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-29T02:08:06.376056Z","caller":"traceutil/trace.go:171","msg":"trace[1016611496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1058; }","duration":"788.418904ms","start":"2026-05-29T02:08:05.587614Z","end":"2026-05-29T02:08:06.376032Z","steps":["trace[1016611496] 'count revisions from in-memory index tree' (duration: 788.191949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:06.376102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:05.587564Z","time spent":"788.525387ms","remote":"127.0.0.1:34828","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 "} {"level":"warn","ts":"2026-05-29T02:08:06.376461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.266903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:06.3766Z","caller":"traceutil/trace.go:171","msg":"trace[199112805] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1058; }","duration":"567.423724ms","start":"2026-05-29T02:08:05.809162Z","end":"2026-05-29T02:08:06.376586Z","steps":["trace[199112805] 'count revisions from in-memory index tree' (duration: 566.1726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:06.37664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:05.809128Z","time spent":"567.498545ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:08:06.901182Z","caller":"traceutil/trace.go:171","msg":"trace[1972262052] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"449.689775ms","start":"2026-05-29T02:08:06.451458Z","end":"2026-05-29T02:08:06.901148Z","steps":["trace[1972262052] 'process raft request' (duration: 411.140003ms)","trace[1972262052] 'compare' (duration: 38.401658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:06.901359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:06.45143Z","time spent":"449.835908ms","remote":"127.0.0.1:41686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:08:06.947619Z","caller":"traceutil/trace.go:171","msg":"trace[664000959] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"177.624636ms","start":"2026-05-29T02:08:06.769971Z","end":"2026-05-29T02:08:06.947595Z","steps":["trace[664000959] 'process raft request' (duration: 177.544224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:06.947652Z","caller":"traceutil/trace.go:171","msg":"trace[251995975] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"203.143329ms","start":"2026-05-29T02:08:06.744465Z","end":"2026-05-29T02:08:06.947608Z","steps":["trace[251995975] 'process raft request' (duration: 202.914893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:07.320835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.908386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:07.320943Z","caller":"traceutil/trace.go:171","msg":"trace[719209215] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"170.055437ms","start":"2026-05-29T02:08:07.150869Z","end":"2026-05-29T02:08:07.320924Z","steps":["trace[719209215] 'process raft request' (duration: 42.979376ms)","trace[719209215] 'compare' (duration: 126.787554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:07.92741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.017899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T02:08:07.927467Z","caller":"traceutil/trace.go:171","msg":"trace[1844630414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"488.123191ms","start":"2026-05-29T02:08:07.43933Z","end":"2026-05-29T02:08:07.927453Z","steps":["trace[1844630414] 'range keys from in-memory index tree' (duration: 487.851124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:07.927496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.43931Z","time spent":"488.178732ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T02:08:07.927481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.14914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-29T02:08:07.927528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.144715ms","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-29T02:08:07.92756Z","caller":"traceutil/trace.go:171","msg":"trace[1998686435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"475.207546ms","start":"2026-05-29T02:08:07.452344Z","end":"2026-05-29T02:08:07.927551Z","steps":["trace[1998686435] 'range keys from in-memory index tree' (duration: 475.049702ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:07.927557Z","caller":"traceutil/trace.go:171","msg":"trace[991048697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"449.287303ms","start":"2026-05-29T02:08:07.478249Z","end":"2026-05-29T02:08:07.927536Z","steps":["trace[991048697] 'range keys from in-memory index tree' (duration: 448.955845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:07.92758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.452325Z","time spent":"475.250677ms","remote":"127.0.0.1:41898","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-29T02:08:07.927598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.478225Z","time spent":"449.364505ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T02:08:08.330133Z","caller":"traceutil/trace.go:171","msg":"trace[861183604] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"396.910602ms","start":"2026-05-29T02:08:07.932876Z","end":"2026-05-29T02:08:08.329787Z","steps":["trace[861183604] 'process raft request' (duration: 396.756568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:08.330475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.932853Z","time spent":"397.351165ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:09.305269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.045319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:09.3055Z","caller":"traceutil/trace.go:171","msg":"trace[111453589] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1110; }","duration":"768.988118ms","start":"2026-05-29T02:08:08.536475Z","end":"2026-05-29T02:08:09.305463Z","steps":["trace[111453589] 'read index received' (duration: 126.532147ms)","trace[111453589] 'applied index is now lower than readState.Index' (duration: 642.454691ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:09.305582Z","caller":"traceutil/trace.go:171","msg":"trace[1070983350] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.372466089s","start":"2026-05-29T02:08:07.933098Z","end":"2026-05-29T02:08:09.305564Z","steps":["trace[1070983350] 'process raft request' (duration: 729.970576ms)","trace[1070983350] 'compare' (duration: 639.69088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:09.305646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.933089Z","time spent":"1.37251195s","remote":"127.0.0.1:41898","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-29T02:08:09.305688Z","caller":"traceutil/trace.go:171","msg":"trace[1982184534] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"1.372163881s","start":"2026-05-29T02:08:07.933492Z","end":"2026-05-29T02:08:09.305656Z","steps":["trace[1982184534] 'process raft request' (duration: 1.371866114s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:09.305825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:07.933483Z","time spent":"1.372258594s","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:09.306213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.724478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:08:09.306305Z","caller":"traceutil/trace.go:171","msg":"trace[1346205245] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1066; }","duration":"769.81071ms","start":"2026-05-29T02:08:08.536462Z","end":"2026-05-29T02:08:09.306273Z","steps":["trace[1346205245] 'agreement among raft nodes before linearized reading' (duration: 769.227795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:09.30635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:08.536419Z","time spent":"769.918903ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:08:09.371861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.776739ms","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-29T02:08:09.371932Z","caller":"traceutil/trace.go:171","msg":"trace[579372308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"207.905152ms","start":"2026-05-29T02:08:09.164011Z","end":"2026-05-29T02:08:09.371916Z","steps":["trace[579372308] 'agreement among raft nodes before linearized reading' (duration: 207.689777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:09.371924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.756138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T02:08:09.371923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.45089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:09.372021Z","caller":"traceutil/trace.go:171","msg":"trace[1493234373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"493.897382ms","start":"2026-05-29T02:08:08.878094Z","end":"2026-05-29T02:08:09.371992Z","steps":["trace[1493234373] 'agreement among raft nodes before linearized reading' (duration: 493.744548ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:09.372023Z","caller":"traceutil/trace.go:171","msg":"trace[1570514355] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1067; }","duration":"824.639255ms","start":"2026-05-29T02:08:08.547364Z","end":"2026-05-29T02:08:09.372003Z","steps":["trace[1570514355] 'agreement among raft nodes before linearized reading' (duration: 824.394519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:09.372074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:08.878073Z","time spent":"493.989214ms","remote":"127.0.0.1:41650","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-29T02:08:09.372077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:08.547353Z","time spent":"824.709157ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:08:09.372015Z","caller":"traceutil/trace.go:171","msg":"trace[1931195498] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"418.095223ms","start":"2026-05-29T02:08:08.953876Z","end":"2026-05-29T02:08:09.371972Z","steps":["trace[1931195498] 'process raft request' (duration: 417.675012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:09.372234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:08.953863Z","time spent":"418.291788ms","remote":"127.0.0.1:41898","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-29T02:08:09.481855Z","caller":"traceutil/trace.go:171","msg":"trace[1419612128] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"105.372649ms","start":"2026-05-29T02:08:09.376451Z","end":"2026-05-29T02:08:09.481824Z","steps":["trace[1419612128] 'process raft request' (duration: 60.851021ms)","trace[1419612128] 'compare' (duration: 44.399075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:10.464602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.513795ms","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-29T02:08:10.464936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.370814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:10.465056Z","caller":"traceutil/trace.go:171","msg":"trace[403600250] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1070; }","duration":"345.751323ms","start":"2026-05-29T02:08:10.119284Z","end":"2026-05-29T02:08:10.465035Z","steps":["trace[403600250] 'count revisions from in-memory index tree' (duration: 345.2557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:10.465124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:10.119259Z","time spent":"345.851526ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:08:10.465001Z","caller":"traceutil/trace.go:171","msg":"trace[606288006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"129.974507ms","start":"2026-05-29T02:08:10.335004Z","end":"2026-05-29T02:08:10.464979Z","steps":["trace[606288006] 'range keys from in-memory index tree' (duration: 129.324541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:10.464608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.633282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:08:10.465693Z","caller":"traceutil/trace.go:171","msg":"trace[1107568394] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1070; }","duration":"323.775632ms","start":"2026-05-29T02:08:10.141895Z","end":"2026-05-29T02:08:10.465671Z","steps":["trace[1107568394] 'count revisions from in-memory index tree' (duration: 322.53131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:10.465745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:10.141878Z","time spent":"323.850674ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:08:10.897811Z","caller":"traceutil/trace.go:171","msg":"trace[1274039850] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"428.80216ms","start":"2026-05-29T02:08:10.468899Z","end":"2026-05-29T02:08:10.897701Z","steps":["trace[1274039850] 'process raft request' (duration: 428.607005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:10.89805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:10.468877Z","time spent":"429.087278ms","remote":"127.0.0.1:41898","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-29T02:08:27.421339Z","caller":"traceutil/trace.go:171","msg":"trace[1168396115] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"243.234859ms","start":"2026-05-29T02:08:27.178084Z","end":"2026-05-29T02:08:27.421319Z","steps":["trace[1168396115] 'process raft request' (duration: 243.109965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:27.927233Z","caller":"traceutil/trace.go:171","msg":"trace[1168228426] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"418.579885ms","start":"2026-05-29T02:08:27.508631Z","end":"2026-05-29T02:08:27.927211Z","steps":["trace[1168228426] 'read index received' (duration: 406.277905ms)","trace[1168228426] 'applied index is now lower than readState.Index' (duration: 12.30095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:27.9275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.844611ms","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-29T02:08:27.927537Z","caller":"traceutil/trace.go:171","msg":"trace[1341173324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"418.938624ms","start":"2026-05-29T02:08:27.508589Z","end":"2026-05-29T02:08:27.927528Z","steps":["trace[1341173324] 'agreement among raft nodes before linearized reading' (duration: 418.79003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.927575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:27.50857Z","time spent":"418.999447ms","remote":"127.0.0.1:41898","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-29T02:08:27.927631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.586033ms","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-29T02:08:27.927682Z","caller":"traceutil/trace.go:171","msg":"trace[1521832544] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"451.46766ms","start":"2026-05-29T02:08:27.476206Z","end":"2026-05-29T02:08:27.927674Z","steps":["trace[1521832544] 'process raft request' (duration: 438.837682ms)","trace[1521832544] 'compare' (duration: 11.426407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:27.927662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.435722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:08:27.927723Z","caller":"traceutil/trace.go:171","msg":"trace[496637710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"195.710405ms","start":"2026-05-29T02:08:27.73199Z","end":"2026-05-29T02:08:27.927701Z","steps":["trace[496637710] 'agreement among raft nodes before linearized reading' (duration: 195.458278ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:27.927738Z","caller":"traceutil/trace.go:171","msg":"trace[989750573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"199.556435ms","start":"2026-05-29T02:08:27.728163Z","end":"2026-05-29T02:08:27.92772Z","steps":["trace[989750573] 'agreement among raft nodes before linearized reading' (duration: 199.329598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.927747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:27.476179Z","time spent":"451.527221ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:27.927632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.218397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T02:08:27.927916Z","caller":"traceutil/trace.go:171","msg":"trace[1099900308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"418.541534ms","start":"2026-05-29T02:08:27.50936Z","end":"2026-05-29T02:08:27.927902Z","steps":["trace[1099900308] 'agreement among raft nodes before linearized reading' (duration: 418.087092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.927966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:27.50935Z","time spent":"418.600366ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T02:08:29.598799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879388996267322,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:08:29.773807Z","caller":"traceutil/trace.go:171","msg":"trace[1225443697] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"675.404818ms","start":"2026-05-29T02:08:29.098385Z","end":"2026-05-29T02:08:29.773789Z","steps":["trace[1225443697] 'read index received' (duration: 675.276644ms)","trace[1225443697] 'applied index is now lower than readState.Index' (duration: 127.464µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:29.773967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.552082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T02:08:29.774012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.255658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:29.774043Z","caller":"traceutil/trace.go:171","msg":"trace[2009144065] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1181; }","duration":"286.34271ms","start":"2026-05-29T02:08:29.48769Z","end":"2026-05-29T02:08:29.774032Z","steps":["trace[2009144065] 'agreement among raft nodes before linearized reading' (duration: 286.242678ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:29.774041Z","caller":"traceutil/trace.go:171","msg":"trace[995464817] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1181; }","duration":"675.672535ms","start":"2026-05-29T02:08:29.098351Z","end":"2026-05-29T02:08:29.774023Z","steps":["trace[995464817] 'agreement among raft nodes before linearized reading' (duration: 675.528912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:29.774087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:29.098336Z","time spent":"675.738427ms","remote":"127.0.0.1:35010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:08:29.774481Z","caller":"traceutil/trace.go:171","msg":"trace[843191180] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"717.44537ms","start":"2026-05-29T02:08:29.056965Z","end":"2026-05-29T02:08:29.77441Z","steps":["trace[843191180] 'process raft request' (duration: 716.715911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:29.774558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:29.05695Z","time spent":"717.562004ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:08:30.040754Z","caller":"traceutil/trace.go:171","msg":"trace[1946551319] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"106.514918ms","start":"2026-05-29T02:08:29.934217Z","end":"2026-05-29T02:08:30.040732Z","steps":["trace[1946551319] 'process raft request' (duration: 100.145182ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:44.233793Z","caller":"traceutil/trace.go:171","msg":"trace[451414459] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"113.924368ms","start":"2026-05-29T02:08:44.119835Z","end":"2026-05-29T02:08:44.233759Z","steps":["trace[451414459] 'read index received' (duration: 113.695603ms)","trace[451414459] 'applied index is now lower than readState.Index' (duration: 227.125µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:44.233875Z","caller":"traceutil/trace.go:171","msg":"trace[672909632] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"138.977108ms","start":"2026-05-29T02:08:44.09486Z","end":"2026-05-29T02:08:44.233838Z","steps":["trace[672909632] 'process raft request' (duration: 138.755253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:44.234084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.223935ms","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-29T02:08:44.234172Z","caller":"traceutil/trace.go:171","msg":"trace[493120787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"114.363559ms","start":"2026-05-29T02:08:44.119784Z","end":"2026-05-29T02:08:44.234148Z","steps":["trace[493120787] 'agreement among raft nodes before linearized reading' (duration: 114.117023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:44.234241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.441981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T02:08:44.234288Z","caller":"traceutil/trace.go:171","msg":"trace[1156825835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"102.535343ms","start":"2026-05-29T02:08:44.131741Z","end":"2026-05-29T02:08:44.234276Z","steps":["trace[1156825835] 'agreement among raft nodes before linearized reading' (duration: 102.355528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:44.23433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.504156ms","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-29T02:08:44.234476Z","caller":"traceutil/trace.go:171","msg":"trace[1744463089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"111.625849ms","start":"2026-05-29T02:08:44.122777Z","end":"2026-05-29T02:08:44.234402Z","steps":["trace[1744463089] 'agreement among raft nodes before linearized reading' (duration: 111.357082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:44.234358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.552027ms","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-29T02:08:44.234642Z","caller":"traceutil/trace.go:171","msg":"trace[1626575443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"111.864095ms","start":"2026-05-29T02:08:44.122721Z","end":"2026-05-29T02:08:44.234585Z","steps":["trace[1626575443] 'agreement among raft nodes before linearized reading' (duration: 111.359191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:44.656973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.856866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:44.65712Z","caller":"traceutil/trace.go:171","msg":"trace[1085984771] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"419.753739ms","start":"2026-05-29T02:08:44.237339Z","end":"2026-05-29T02:08:44.657093Z","steps":["trace[1085984771] 'process raft request' (duration: 258.684277ms)","trace[1085984771] 'compare' (duration: 160.728083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:44.657199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:44.237316Z","time spent":"419.848061ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:45.300819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.355767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:45.301065Z","caller":"traceutil/trace.go:171","msg":"trace[706567620] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.061454708s","start":"2026-05-29T02:08:44.239588Z","end":"2026-05-29T02:08:45.301043Z","steps":["trace[706567620] 'process raft request' (duration: 1.061401837s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:45.301147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:44.239578Z","time spent":"1.061533952s","remote":"127.0.0.1:41898","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-29T02:08:45.301194Z","caller":"traceutil/trace.go:171","msg":"trace[623015355] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.063192114s","start":"2026-05-29T02:08:44.237973Z","end":"2026-05-29T02:08:45.301166Z","steps":["trace[623015355] 'process raft request' (duration: 893.471077ms)","trace[623015355] 'compare' (duration: 169.182633ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:45.30138Z","caller":"traceutil/trace.go:171","msg":"trace[692642421] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.063323137s","start":"2026-05-29T02:08:44.238038Z","end":"2026-05-29T02:08:45.301361Z","steps":["trace[692642421] 'process raft request' (duration: 1.062875825s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:45.301395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:44.237953Z","time spent":"1.063371619s","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:45.302775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:44.238015Z","time spent":"1.06341002s","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:46.370866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879388996267453,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:08:46.611598Z","caller":"traceutil/trace.go:171","msg":"trace[133076444] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"789.96041ms","start":"2026-05-29T02:08:45.821616Z","end":"2026-05-29T02:08:46.611576Z","steps":["trace[133076444] 'process raft request' (duration: 701.054341ms)","trace[133076444] 'compare' (duration: 88.642002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:46.611709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:45.821599Z","time spent":"790.050083ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:08:46.617611Z","caller":"traceutil/trace.go:171","msg":"trace[991989765] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1290; }","duration":"747.550399ms","start":"2026-05-29T02:08:45.870037Z","end":"2026-05-29T02:08:46.617588Z","steps":["trace[991989765] 'read index received' (duration: 652.670505ms)","trace[991989765] 'applied index is now lower than readState.Index' (duration: 94.878974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:46.617809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.757224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:46.61786Z","caller":"traceutil/trace.go:171","msg":"trace[1605400437] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1237; }","duration":"747.858777ms","start":"2026-05-29T02:08:45.869992Z","end":"2026-05-29T02:08:46.617851Z","steps":["trace[1605400437] 'agreement among raft nodes before linearized reading' (duration: 747.724994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:46.617898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:45.869974Z","time spent":"747.915969ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-05-29T02:08:46.617817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.878821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T02:08:46.617943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.747757ms","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-29T02:08:46.617948Z","caller":"traceutil/trace.go:171","msg":"trace[1908990193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"740.039414ms","start":"2026-05-29T02:08:45.877891Z","end":"2026-05-29T02:08:46.617931Z","steps":["trace[1908990193] 'agreement among raft nodes before linearized reading' (duration: 739.87035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:46.617927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.137019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T02:08:46.617987Z","caller":"traceutil/trace.go:171","msg":"trace[1435059658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"709.84335ms","start":"2026-05-29T02:08:45.908132Z","end":"2026-05-29T02:08:46.617975Z","steps":["trace[1435059658] 'agreement among raft nodes before linearized reading' (duration: 709.686196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:46.617993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:45.877876Z","time spent":"740.107516ms","remote":"127.0.0.1:41650","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-29T02:08:46.617999Z","caller":"traceutil/trace.go:171","msg":"trace[789280633] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1237; }","duration":"281.246852ms","start":"2026-05-29T02:08:46.336738Z","end":"2026-05-29T02:08:46.617985Z","steps":["trace[789280633] 'agreement among raft nodes before linearized reading' (duration: 281.052957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:46.618031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:45.908117Z","time spent":"709.892172ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T02:08:46.617894Z","caller":"traceutil/trace.go:171","msg":"trace[314580381] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"379.204154ms","start":"2026-05-29T02:08:46.238671Z","end":"2026-05-29T02:08:46.617875Z","steps":["trace[314580381] 'process raft request' (duration: 378.798705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:46.618379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:46.238652Z","time spent":"379.678978ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:47.05894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.130495ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-29T02:08:47.059129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:46.61926Z","time spent":"439.863161ms","remote":"127.0.0.1:41686","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T02:08:47.059243Z","caller":"traceutil/trace.go:171","msg":"trace[310405597] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"397.775128ms","start":"2026-05-29T02:08:46.661446Z","end":"2026-05-29T02:08:47.059221Z","steps":["trace[310405597] 'read index received' (duration: 204.348195ms)","trace[310405597] 'applied index is now lower than readState.Index' (duration: 193.425673ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:47.059361Z","caller":"traceutil/trace.go:171","msg":"trace[1936976651] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"438.69736ms","start":"2026-05-29T02:08:46.620648Z","end":"2026-05-29T02:08:47.059346Z","steps":["trace[1936976651] 'process raft request' (duration: 438.416033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:47.059468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.017123ms","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-29T02:08:47.0595Z","caller":"traceutil/trace.go:171","msg":"trace[268105970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"398.089445ms","start":"2026-05-29T02:08:46.661401Z","end":"2026-05-29T02:08:47.05949Z","steps":["trace[268105970] 'agreement among raft nodes before linearized reading' (duration: 398.000473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:47.059518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:46.661385Z","time spent":"398.129797ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T02:08:47.059482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:46.620633Z","time spent":"438.771072ms","remote":"127.0.0.1:41898","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-29T02:08:47.747876Z","caller":"traceutil/trace.go:171","msg":"trace[1596684406] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1297; }","duration":"211.725586ms","start":"2026-05-29T02:08:47.536123Z","end":"2026-05-29T02:08:47.747849Z","steps":["trace[1596684406] 'read index received' (duration: 128.606599ms)","trace[1596684406] 'applied index is now lower than readState.Index' (duration: 83.117867ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:47.747924Z","caller":"traceutil/trace.go:171","msg":"trace[46882042] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"437.112548ms","start":"2026-05-29T02:08:47.310781Z","end":"2026-05-29T02:08:47.747894Z","steps":["trace[46882042] 'process raft request' (duration: 436.927514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:47.748026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.878961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T02:08:47.748064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:47.310764Z","time spent":"437.227092ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:08:47.74808Z","caller":"traceutil/trace.go:171","msg":"trace[273150260] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1243; }","duration":"211.981544ms","start":"2026-05-29T02:08:47.536081Z","end":"2026-05-29T02:08:47.748062Z","steps":["trace[273150260] 'agreement among raft nodes before linearized reading' (duration: 211.880911ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:47.748047Z","caller":"traceutil/trace.go:171","msg":"trace[1999372521] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"437.333935ms","start":"2026-05-29T02:08:47.310689Z","end":"2026-05-29T02:08:47.748023Z","steps":["trace[1999372521] 'process raft request' (duration: 354.133624ms)","trace[1999372521] 'compare' (duration: 82.599995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:47.748287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:47.310676Z","time spent":"437.504349ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:08:48.236849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.401586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:48.236922Z","caller":"traceutil/trace.go:171","msg":"trace[987343155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"358.521319ms","start":"2026-05-29T02:08:47.87838Z","end":"2026-05-29T02:08:48.236901Z","steps":["trace[987343155] 'range keys from in-memory index tree' (duration: 358.274582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:48.236969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:47.878359Z","time spent":"358.60186ms","remote":"127.0.0.1:41664","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-29T02:08:48.237126Z","caller":"traceutil/trace.go:171","msg":"trace[466545901] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"247.004604ms","start":"2026-05-29T02:08:47.990096Z","end":"2026-05-29T02:08:48.2371Z","steps":["trace[466545901] 'process raft request' (duration: 236.416479ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:48.256022Z","caller":"traceutil/trace.go:171","msg":"trace[1044581074] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"232.986449ms","start":"2026-05-29T02:08:48.023013Z","end":"2026-05-29T02:08:48.256Z","steps":["trace[1044581074] 'process raft request' (duration: 232.844056ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:49.791898Z","caller":"traceutil/trace.go:171","msg":"trace[2131978092] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"253.224375ms","start":"2026-05-29T02:08:49.538638Z","end":"2026-05-29T02:08:49.791862Z","steps":["trace[2131978092] 'process raft request' (duration: 181.753589ms)","trace[2131978092] 'compare' (duration: 71.22366ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:50.050777Z","caller":"traceutil/trace.go:171","msg":"trace[782868082] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"254.258781ms","start":"2026-05-29T02:08:49.796498Z","end":"2026-05-29T02:08:50.050756Z","steps":["trace[782868082] 'process raft request' (duration: 254.164639ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:50.050781Z","caller":"traceutil/trace.go:171","msg":"trace[1235059423] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"254.363485ms","start":"2026-05-29T02:08:49.796404Z","end":"2026-05-29T02:08:50.050768Z","steps":["trace[1235059423] 'process raft request' (duration: 212.072147ms)","trace[1235059423] 'compare' (duration: 41.942609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:58.822738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.313064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T02:08:58.822824Z","caller":"traceutil/trace.go:171","msg":"trace[1250603385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"734.432109ms","start":"2026-05-29T02:08:58.088373Z","end":"2026-05-29T02:08:58.822805Z","steps":["trace[1250603385] 'range keys from in-memory index tree' (duration: 734.110589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:58.822873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:58.088363Z","time spent":"734.49794ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T02:08:58.823038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.475052ms","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-29T02:08:58.823109Z","caller":"traceutil/trace.go:171","msg":"trace[1564893690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"736.566224ms","start":"2026-05-29T02:08:58.086515Z","end":"2026-05-29T02:08:58.823082Z","steps":["trace[1564893690] 'range keys from in-memory index tree' (duration: 736.287447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:58.823138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:58.086496Z","time spent":"736.636146ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T02:08:58.826034Z","caller":"traceutil/trace.go:171","msg":"trace[995638717] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"386.585427ms","start":"2026-05-29T02:08:58.439429Z","end":"2026-05-29T02:08:58.826014Z","steps":["trace[995638717] 'process raft request' (duration: 386.428123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:58.826161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:58.439404Z","time spent":"386.69095ms","remote":"127.0.0.1:41898","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-29T02:08:58.826369Z","caller":"traceutil/trace.go:171","msg":"trace[107087725] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1392; }","duration":"189.977804ms","start":"2026-05-29T02:08:58.636372Z","end":"2026-05-29T02:08:58.82635Z","steps":["trace[107087725] 'read index received' (duration: 189.970424ms)","trace[107087725] 'applied index is now lower than readState.Index' (duration: 5.94µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:58.826502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.123057ms","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-29T02:08:58.826532Z","caller":"traceutil/trace.go:171","msg":"trace[953228240] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1334; }","duration":"190.191989ms","start":"2026-05-29T02:08:58.636331Z","end":"2026-05-29T02:08:58.826523Z","steps":["trace[953228240] 'agreement among raft nodes before linearized reading' (duration: 190.122647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:58.828428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.460679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:08:58.828416Z","caller":"traceutil/trace.go:171","msg":"trace[1264536295] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"175.787915ms","start":"2026-05-29T02:08:58.652606Z","end":"2026-05-29T02:08:58.828394Z","steps":["trace[1264536295] 'process raft request' (duration: 175.682791ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:58.828465Z","caller":"traceutil/trace.go:171","msg":"trace[1177222531] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1335; }","duration":"105.53714ms","start":"2026-05-29T02:08:58.722917Z","end":"2026-05-29T02:08:58.828455Z","steps":["trace[1177222531] 'agreement among raft nodes before linearized reading' (duration: 105.479029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:58.828845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.877508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-29T02:08:58.828902Z","caller":"traceutil/trace.go:171","msg":"trace[968526014] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"182.93806ms","start":"2026-05-29T02:08:58.645933Z","end":"2026-05-29T02:08:58.828871Z","steps":["trace[968526014] 'agreement among raft nodes before linearized reading' (duration: 182.421167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:58.940243Z","caller":"traceutil/trace.go:171","msg":"trace[510594742] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"112.646045ms","start":"2026-05-29T02:08:58.827573Z","end":"2026-05-29T02:08:58.940219Z","steps":["trace[510594742] 'process raft request' (duration: 112.551212ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:58.940261Z","caller":"traceutil/trace.go:171","msg":"trace[623206435] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"112.83993ms","start":"2026-05-29T02:08:58.827374Z","end":"2026-05-29T02:08:58.940214Z","steps":["trace[623206435] 'process raft request' (duration: 110.845608ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:58.940522Z","caller":"traceutil/trace.go:171","msg":"trace[1492341670] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"107.713157ms","start":"2026-05-29T02:08:58.832794Z","end":"2026-05-29T02:08:58.940507Z","steps":["trace[1492341670] 'process raft request' (duration: 107.384228ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:09:04.130942Z","caller":"traceutil/trace.go:171","msg":"trace[1155584902] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"181.859902ms","start":"2026-05-29T02:09:03.949053Z","end":"2026-05-29T02:09:04.130913Z","steps":["trace[1155584902] 'process raft request' (duration: 181.647515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:09:13.430134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.054808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:09:13.430295Z","caller":"traceutil/trace.go:171","msg":"trace[1939659976] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"139.152573ms","start":"2026-05-29T02:09:13.29111Z","end":"2026-05-29T02:09:13.430263Z","steps":["trace[1939659976] 'read index received' (duration: 47.681µs)","trace[1939659976] 'applied index is now lower than readState.Index' (duration: 139.103212ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:13.430361Z","caller":"traceutil/trace.go:171","msg":"trace[1180353282] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"225.581828ms","start":"2026-05-29T02:09:13.204735Z","end":"2026-05-29T02:09:13.430316Z","steps":["trace[1180353282] 'process raft request' (duration: 68.238433ms)","trace[1180353282] 'compare' (duration: 156.932004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:09:13.430546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.410229ms","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-29T02:09:13.430637Z","caller":"traceutil/trace.go:171","msg":"trace[1744233720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1405; }","duration":"139.540902ms","start":"2026-05-29T02:09:13.291072Z","end":"2026-05-29T02:09:13.430613Z","steps":["trace[1744233720] 'agreement among raft nodes before linearized reading' (duration: 139.258315ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:09:21.080722Z","caller":"traceutil/trace.go:171","msg":"trace[2120779893] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"129.649237ms","start":"2026-05-29T02:09:20.951043Z","end":"2026-05-29T02:09:21.080692Z","steps":["trace[2120779893] 'process raft request' (duration: 126.945306ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:09:21.535702Z","caller":"traceutil/trace.go:171","msg":"trace[1287578879] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"242.464485ms","start":"2026-05-29T02:09:21.293212Z","end":"2026-05-29T02:09:21.535677Z","steps":["trace[1287578879] 'process raft request' (duration: 181.488982ms)","trace[1287578879] 'compare' (duration: 60.789588ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:21.702404Z","caller":"traceutil/trace.go:171","msg":"trace[1136235863] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"161.844472ms","start":"2026-05-29T02:09:21.540526Z","end":"2026-05-29T02:09:21.70237Z","steps":["trace[1136235863] 'process raft request' (duration: 123.348812ms)","trace[1136235863] 'compare' (duration: 38.116491ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:23.87422Z","caller":"traceutil/trace.go:171","msg":"trace[1905264854] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"109.274427ms","start":"2026-05-29T02:09:23.764916Z","end":"2026-05-29T02:09:23.87419Z","steps":["trace[1905264854] 'process raft request' (duration: 86.091055ms)","trace[1905264854] 'compare' (duration: 23.055439ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:41.677755Z","caller":"traceutil/trace.go:171","msg":"trace[1356466003] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"161.995883ms","start":"2026-05-29T02:09:41.515729Z","end":"2026-05-29T02:09:41.677725Z","steps":["trace[1356466003] 'process raft request' (duration: 78.61046ms)","trace[1356466003] 'compare' (duration: 83.261869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:09:41.8675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.733237ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T02:09:41.881223Z","caller":"traceutil/trace.go:171","msg":"trace[447325029] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"152.368581ms","start":"2026-05-29T02:09:41.728821Z","end":"2026-05-29T02:09:41.881189Z","steps":["trace[447325029] 'process raft request' (duration: 152.075824ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:11:12.545195Z","caller":"traceutil/trace.go:171","msg":"trace[1128415051] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"109.808188ms","start":"2026-05-29T02:11:12.435353Z","end":"2026-05-29T02:11:12.545162Z","steps":["trace[1128415051] 'process raft request' (duration: 79.82508ms)","trace[1128415051] 'compare' (duration: 29.474634ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:12:40.851068Z","caller":"traceutil/trace.go:171","msg":"trace[979842611] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"124.424437ms","start":"2026-05-29T02:12:40.726614Z","end":"2026-05-29T02:12:40.851039Z","steps":["trace[979842611] 'process raft request' (duration: 124.231843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:12:46.827719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.449764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:12:46.827816Z","caller":"traceutil/trace.go:171","msg":"trace[1821160378] linearizableReadLoop","detail":"{readStateIndex:2512; appliedIndex:2511; }","duration":"397.392374ms","start":"2026-05-29T02:12:46.430411Z","end":"2026-05-29T02:12:46.827803Z","steps":["trace[1821160378] 'read index received' (duration: 177.786296ms)","trace[1821160378] 'applied index is now lower than readState.Index' (duration: 219.604808ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:12:46.827873Z","caller":"traceutil/trace.go:171","msg":"trace[623024331] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"398.131004ms","start":"2026-05-29T02:12:46.429693Z","end":"2026-05-29T02:12:46.827824Z","steps":["trace[623024331] 'process raft request' (duration: 178.465865ms)","trace[623024331] 'compare' (duration: 219.197018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:12:46.828028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:12:46.429679Z","time spent":"398.269198ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:12:46.82817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.755385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T02:12:46.828222Z","caller":"traceutil/trace.go:171","msg":"trace[346407132] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2407; }","duration":"397.848938ms","start":"2026-05-29T02:12:46.430364Z","end":"2026-05-29T02:12:46.828212Z","steps":["trace[346407132] 'agreement among raft nodes before linearized reading' (duration: 397.512689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:12:46.82825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:12:46.430342Z","time spent":"397.900999ms","remote":"127.0.0.1:41686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-29T02:12:46.828337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.169462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-29T02:12:46.82842Z","caller":"traceutil/trace.go:171","msg":"trace[598710388] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2407; }","duration":"261.305457ms","start":"2026-05-29T02:12:46.567093Z","end":"2026-05-29T02:12:46.828398Z","steps":["trace[598710388] 'agreement among raft nodes before linearized reading' (duration: 261.103852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:12:46.828429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.352899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:12:46.828675Z","caller":"traceutil/trace.go:171","msg":"trace[912991261] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2407; }","duration":"345.652717ms","start":"2026-05-29T02:12:46.483006Z","end":"2026-05-29T02:12:46.828659Z","steps":["trace[912991261] 'agreement among raft nodes before linearized reading' (duration: 345.349229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:12:46.828919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:12:46.482987Z","time spent":"345.828432ms","remote":"127.0.0.1:34952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "}