{"level":"warn","ts":"2026-03-20T05:30:53.087614Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-20T05:30:53.087706Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-03-20T05:30:53.087766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-20T05:30:53.087781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]}
{"level":"info","ts":"2026-03-20T05:30:53.087799Z","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-03-20T05:30:53.088401Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]}
{"level":"info","ts":"2026-03-20T05:30:53.088468Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-03-20T05:30:53.092968Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.253929ms"}
{"level":"info","ts":"2026-03-20T05:30:53.103167Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"}
{"level":"info","ts":"2026-03-20T05:30:53.103229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-20T05:30:53.103247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"}
{"level":"info","ts":"2026-03-20T05:30:53.103257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-20T05:30:53.103268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"}
{"level":"info","ts":"2026-03-20T05:30:53.1033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"}
{"level":"warn","ts":"2026-03-20T05:30:53.113666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-20T05:30:53.115649Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-20T05:30:53.119157Z","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-03-20T05:30:53.124285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-20T05:30:53.125753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-20T05:30:53.125944Z","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-03-20T05:30:53.125982Z","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-03-20T05:30:53.125989Z","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-03-20T05:30:53.135907Z","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-03-20T05:30:53.136302Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-20T05:30:53.136333Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"}
{"level":"info","ts":"2026-03-20T05:30:53.136385Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"}
{"level":"info","ts":"2026-03-20T05:30:53.136354Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-20T05:30:53.253345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"}
{"level":"info","ts":"2026-03-20T05:30:53.253457Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]}
{"level":"info","ts":"2026-03-20T05:30:53.803431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-20T05:30:53.803563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-20T05:30:53.803594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"}
{"level":"info","ts":"2026-03-20T05:30:53.803614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"}
{"level":"info","ts":"2026-03-20T05:30:53.803625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"}
{"level":"info","ts":"2026-03-20T05:30:53.803641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"}
{"level":"info","ts":"2026-03-20T05:30:53.803661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"}
{"level":"info","ts":"2026-03-20T05:30:54.151491Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-20T05:30:54.151501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-20T05:30:54.151504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-20T05:30:54.151563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-20T05:30:54.151749Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-20T05:30:54.151803Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-20T05:30:54.155165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-20T05:30:54.155645Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-20T05:30:54.155775Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-20T05:30:54.155836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-20T05:30:54.156602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"}
{"level":"info","ts":"2026-03-20T05:30:56.271574Z","caller":"traceutil/trace.go:171","msg":"trace[1980609915] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"103.777159ms","start":"2026-03-20T05:30:56.167772Z","end":"2026-03-20T05:30:56.271549Z","steps":["trace[1980609915] 'process raft request'  (duration: 19.178207ms)","trace[1980609915] 'compare'  (duration: 84.503491ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:30:56.644082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.465837ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693827086 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/etcd-instance.189e75a49c6b7fe0\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/etcd-instance.189e75a49c6b7fe0\" value_size:599 lease:4487165268839051202 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-03-20T05:30:56.644221Z","caller":"traceutil/trace.go:171","msg":"trace[1535833088] linearizableReadLoop","detail":"{readStateIndex:111; appliedIndex:110; }","duration":"265.64101ms","start":"2026-03-20T05:30:56.378558Z","end":"2026-03-20T05:30:56.6442Z","steps":["trace[1535833088] 'read index received'  (duration: 134.505831ms)","trace[1535833088] 'applied index is now lower than readState.Index'  (duration: 131.133879ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:30:56.64427Z","caller":"traceutil/trace.go:171","msg":"trace[1285144882] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"334.5525ms","start":"2026-03-20T05:30:56.309674Z","end":"2026-03-20T05:30:56.644227Z","steps":["trace[1285144882] 'process raft request'  (duration: 203.437792ms)","trace[1285144882] 'compare'  (duration: 130.246723ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:30:56.64442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.493367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:56.644463Z","caller":"traceutil/trace.go:171","msg":"trace[2039488135] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:106; }","duration":"265.579849ms","start":"2026-03-20T05:30:56.378874Z","end":"2026-03-20T05:30:56.644453Z","steps":["trace[2039488135] 'agreement among raft nodes before linearized reading'  (duration: 265.464167ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:56.644417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:56.309655Z","time spent":"334.691224ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/etcd-instance.189e75a49c6b7fe0\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/etcd-instance.189e75a49c6b7fe0\" value_size:599 lease:4487165268839051202 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:30:56.644562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.875895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:56.644718Z","caller":"traceutil/trace.go:171","msg":"trace[1664955040] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:106; }","duration":"266.196421ms","start":"2026-03-20T05:30:56.3785Z","end":"2026-03-20T05:30:56.644696Z","steps":["trace[1664955040] 'agreement among raft nodes before linearized reading'  (duration: 265.861915ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:56.644576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.443123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:56.644885Z","caller":"traceutil/trace.go:171","msg":"trace[1416580910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:106; }","duration":"161.754749ms","start":"2026-03-20T05:30:56.483075Z","end":"2026-03-20T05:30:56.644829Z","steps":["trace[1416580910] 'agreement among raft nodes before linearized reading'  (duration: 161.343272ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:57.150001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693827090,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:30:58.034548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.282927ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693827091 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-instance.189e75a49c92cfe4\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-instance.189e75a49c92cfe4\" value_size:639 lease:4487165268839051202 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-03-20T05:30:58.034666Z","caller":"traceutil/trace.go:171","msg":"trace[1719014908] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"1.386542676s","start":"2026-03-20T05:30:56.648103Z","end":"2026-03-20T05:30:58.034645Z","steps":["trace[1719014908] 'process raft request'  (duration: 967.092165ms)","trace[1719014908] 'compare'  (duration: 419.125334ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:30:58.034772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:56.648087Z","time spent":"1.386659389s","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-instance.189e75a49c92cfe4\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-instance.189e75a49c92cfe4\" value_size:639 lease:4487165268839051202 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:30:58.757765Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142565168s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-20T05:30:58.793008Z","caller":"traceutil/trace.go:171","msg":"trace[130504529] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:111; }","duration":"2.143495713s","start":"2026-03-20T05:30:56.649488Z","end":"2026-03-20T05:30:58.792984Z","steps":["trace[130504529] 'read index received'  (duration: 965.71755ms)","trace[130504529] 'applied index is now lower than readState.Index'  (duration: 1.177777233s)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:30:58.793095Z","caller":"traceutil/trace.go:171","msg":"trace[812032260] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"2.143947441s","start":"2026-03-20T05:30:56.649097Z","end":"2026-03-20T05:30:58.793045Z","steps":["trace[812032260] 'process raft request'  (duration: 2.108885799s)","trace[812032260] 'compare'  (duration: 34.795488ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:30:58.793134Z","caller":"traceutil/trace.go:171","msg":"trace[2060806037] transaction","detail":"{read_only:false; number_of_response:0; response_revision:108; }","duration":"2.059664233s","start":"2026-03-20T05:30:56.733445Z","end":"2026-03-20T05:30:58.793109Z","steps":["trace[2060806037] 'process raft request'  (duration: 2.059499941s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.793223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143708847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:58.793263Z","caller":"traceutil/trace.go:171","msg":"trace[134859864] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:108; }","duration":"2.143796708s","start":"2026-03-20T05:30:56.649455Z","end":"2026-03-20T05:30:58.793252Z","steps":["trace[134859864] 'agreement among raft nodes before linearized reading'  (duration: 2.143675326s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.793254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:56.649077Z","time spent":"2.144106974s","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/priorityclasses/system-node-critical\" mod_revision:0 > success:<request_put:<key:\"/registry/priorityclasses/system-node-critical\" value_size:375 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:30:58.793294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:56.733411Z","time spent":"2.059786276s","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-vip-instance\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/kube-vip-instance\" value_size:3401 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:30:58.793299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:56.649444Z","time spent":"2.143846889s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "}
{"level":"info","ts":"2026-03-20T05:30:58.949334Z","caller":"traceutil/trace.go:171","msg":"trace[952200932] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"156.214676ms","start":"2026-03-20T05:30:58.793101Z","end":"2026-03-20T05:30:58.949316Z","steps":["trace[952200932] 'read index received'  (duration: 146.593857ms)","trace[952200932] 'applied index is now lower than readState.Index'  (duration: 9.619729ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:30:58.949491Z","caller":"traceutil/trace.go:171","msg":"trace[1548990039] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"909.834611ms","start":"2026-03-20T05:30:58.039643Z","end":"2026-03-20T05:30:58.949477Z","steps":["trace[1548990039] 'process raft request'  (duration: 900.039878ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.94959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465637097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-03-20T05:30:58.949629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:58.039615Z","time spent":"909.939662ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/kube-scheduler-instance.189e75a49dc9f067\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-scheduler-instance.189e75a49dc9f067\" value_size:639 lease:4487165268839051202 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:30:58.949594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318390409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"info","ts":"2026-03-20T05:30:58.949791Z","caller":"traceutil/trace.go:171","msg":"trace[1133835548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"1.465879081s","start":"2026-03-20T05:30:57.483872Z","end":"2026-03-20T05:30:58.949751Z","steps":["trace[1133835548] 'agreement among raft nodes before linearized reading'  (duration: 1.465664707s)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:30:58.949776Z","caller":"traceutil/trace.go:171","msg":"trace[1303150499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:109; }","duration":"1.318601843s","start":"2026-03-20T05:30:57.631145Z","end":"2026-03-20T05:30:58.949747Z","steps":["trace[1303150499] 'agreement among raft nodes before linearized reading'  (duration: 1.318270287s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.949849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:57.483851Z","time spent":"1.465985333s","remote":"127.0.0.1:54314","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-03-20T05:30:58.949918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:57.631126Z","time spent":"1.318734315s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-20T05:30:58.949664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.911814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-03-20T05:30:58.950168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.635616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:58.950183Z","caller":"traceutil/trace.go:171","msg":"trace[775774595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"267.610516ms","start":"2026-03-20T05:30:58.682554Z","end":"2026-03-20T05:30:58.950165Z","steps":["trace[775774595] 'agreement among raft nodes before linearized reading'  (duration: 266.804422ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.950301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.255038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:58.95042Z","caller":"traceutil/trace.go:171","msg":"trace[772563826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:109; }","duration":"654.412671ms","start":"2026-03-20T05:30:58.295994Z","end":"2026-03-20T05:30:58.950407Z","steps":["trace[772563826] 'agreement among raft nodes before linearized reading'  (duration: 654.264277ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.95046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:58.295974Z","time spent":"654.475622ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-20T05:30:58.950087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.875699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"}
{"level":"info","ts":"2026-03-20T05:30:58.950548Z","caller":"traceutil/trace.go:171","msg":"trace[980012322] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:109; }","duration":"156.369859ms","start":"2026-03-20T05:30:58.794166Z","end":"2026-03-20T05:30:58.950535Z","steps":["trace[980012322] 'agreement among raft nodes before linearized reading'  (duration: 155.765497ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:58.950582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.272439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-20T05:30:58.950683Z","caller":"traceutil/trace.go:171","msg":"trace[794069004] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:109; }","duration":"154.392471ms","start":"2026-03-20T05:30:58.796274Z","end":"2026-03-20T05:30:58.950667Z","steps":["trace[794069004] 'agreement among raft nodes before linearized reading'  (duration: 154.223228ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:30:58.950212Z","caller":"traceutil/trace.go:171","msg":"trace[810489539] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:109; }","duration":"153.691708ms","start":"2026-03-20T05:30:58.796502Z","end":"2026-03-20T05:30:58.950194Z","steps":["trace[810489539] 'agreement among raft nodes before linearized reading'  (duration: 153.617036ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:30:59.220539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.049793ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693827118 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterroles/system:monitoring\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/system:monitoring\" value_size:573 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-03-20T05:30:59.220909Z","caller":"traceutil/trace.go:171","msg":"trace[1534837591] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"186.107212ms","start":"2026-03-20T05:30:59.034783Z","end":"2026-03-20T05:30:59.22089Z","steps":["trace[1534837591] 'process raft request'  (duration: 186.02818ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:30:59.220906Z","caller":"traceutil/trace.go:171","msg":"trace[303254782] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"219.516552ms","start":"2026-03-20T05:30:59.001356Z","end":"2026-03-20T05:30:59.220872Z","steps":["trace[303254782] 'process raft request'  (duration: 64.050231ms)","trace[303254782] 'compare'  (duration: 154.772518ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:28.282732Z","caller":"traceutil/trace.go:171","msg":"trace[1414856040] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"122.692371ms","start":"2026-03-20T05:31:28.16001Z","end":"2026-03-20T05:31:28.282703Z","steps":["trace[1414856040] 'process raft request'  (duration: 122.421186ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:29.704573Z","caller":"traceutil/trace.go:171","msg":"trace[572570277] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"126.85814ms","start":"2026-03-20T05:31:29.577689Z","end":"2026-03-20T05:31:29.704547Z","steps":["trace[572570277] 'process raft request'  (duration: 126.681487ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:29.937398Z","caller":"traceutil/trace.go:171","msg":"trace[530516266] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"158.922635ms","start":"2026-03-20T05:31:29.778442Z","end":"2026-03-20T05:31:29.937364Z","steps":["trace[530516266] 'process raft request'  (duration: 158.465147ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:30.654571Z","caller":"traceutil/trace.go:171","msg":"trace[1386957043] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"367.263429ms","start":"2026-03-20T05:31:30.287283Z","end":"2026-03-20T05:31:30.654547Z","steps":["trace[1386957043] 'read index received'  (duration: 367.191398ms)","trace[1386957043] 'applied index is now lower than readState.Index'  (duration: 70.991µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:30.654721Z","caller":"traceutil/trace.go:171","msg":"trace[353817097] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"476.335709ms","start":"2026-03-20T05:31:30.178334Z","end":"2026-03-20T05:31:30.65467Z","steps":["trace[353817097] 'process raft request'  (duration: 476.084904ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:30.654909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:30.178295Z","time spent":"476.523561ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" value_size:9240 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:31:30.654954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.621257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"}
{"level":"info","ts":"2026-03-20T05:31:30.655166Z","caller":"traceutil/trace.go:171","msg":"trace[639875813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"367.926852ms","start":"2026-03-20T05:31:30.287209Z","end":"2026-03-20T05:31:30.655136Z","steps":["trace[639875813] 'agreement among raft nodes before linearized reading'  (duration: 367.462893ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:30.655233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:30.28719Z","time spent":"368.031264ms","remote":"127.0.0.1:54590","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-03-20T05:31:30.659413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.491778ms","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-03-20T05:31:30.659569Z","caller":"traceutil/trace.go:171","msg":"trace[940768841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"343.675112ms","start":"2026-03-20T05:31:30.315877Z","end":"2026-03-20T05:31:30.659552Z","steps":["trace[940768841] 'agreement among raft nodes before linearized reading'  (duration: 343.461238ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:30.659606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:30.315845Z","time spent":"343.752313ms","remote":"127.0.0.1:54590","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-03-20T05:31:30.65982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.613285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:31:30.65986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.183938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:31:30.659888Z","caller":"traceutil/trace.go:171","msg":"trace[1166541008] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"283.738047ms","start":"2026-03-20T05:31:30.376133Z","end":"2026-03-20T05:31:30.659871Z","steps":["trace[1166541008] 'agreement among raft nodes before linearized reading'  (duration: 283.621244ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:30.659922Z","caller":"traceutil/trace.go:171","msg":"trace[780687087] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:554; }","duration":"175.33081ms","start":"2026-03-20T05:31:30.484577Z","end":"2026-03-20T05:31:30.659907Z","steps":["trace[780687087] 'agreement among raft nodes before linearized reading'  (duration: 175.117756ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:33.403942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.484168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"warn","ts":"2026-03-20T05:31:33.403998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.608943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"}
{"level":"info","ts":"2026-03-20T05:31:33.404046Z","caller":"traceutil/trace.go:171","msg":"trace[558226615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"229.664111ms","start":"2026-03-20T05:31:33.174359Z","end":"2026-03-20T05:31:33.404023Z","steps":["trace[558226615] 'range keys from in-memory index tree'  (duration: 229.232643ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:33.40408Z","caller":"traceutil/trace.go:171","msg":"trace[2143115058] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:577; }","duration":"227.720256ms","start":"2026-03-20T05:31:33.176338Z","end":"2026-03-20T05:31:33.404059Z","steps":["trace[2143115058] 'range keys from in-memory index tree'  (duration: 227.39222ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:33.404062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.903804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"warn","ts":"2026-03-20T05:31:33.404107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.931921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-20T05:31:33.404166Z","caller":"traceutil/trace.go:171","msg":"trace[780275667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"231.042827ms","start":"2026-03-20T05:31:33.173091Z","end":"2026-03-20T05:31:33.404133Z","steps":["trace[780275667] 'range keys from in-memory index tree'  (duration: 230.737311ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:33.404197Z","caller":"traceutil/trace.go:171","msg":"trace[1710798972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:577; }","duration":"212.057674ms","start":"2026-03-20T05:31:33.192094Z","end":"2026-03-20T05:31:33.404152Z","steps":["trace[1710798972] 'range keys from in-memory index tree'  (duration: 211.767868ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:35.591351Z","caller":"traceutil/trace.go:171","msg":"trace[1125170032] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"103.137647ms","start":"2026-03-20T05:31:35.488191Z","end":"2026-03-20T05:31:35.591328Z","steps":["trace[1125170032] 'read index received'  (duration: 102.954854ms)","trace[1125170032] 'applied index is now lower than readState.Index'  (duration: 181.753µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:35.59147Z","caller":"traceutil/trace.go:171","msg":"trace[652900954] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"133.176786ms","start":"2026-03-20T05:31:35.458266Z","end":"2026-03-20T05:31:35.591443Z","steps":["trace[652900954] 'process raft request'  (duration: 132.928972ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:35.591616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.401272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"warn","ts":"2026-03-20T05:31:35.591615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.316281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-20T05:31:35.591665Z","caller":"traceutil/trace.go:171","msg":"trace[1484963632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:584; }","duration":"103.493184ms","start":"2026-03-20T05:31:35.488161Z","end":"2026-03-20T05:31:35.591654Z","steps":["trace[1484963632] 'agreement among raft nodes before linearized reading'  (duration: 103.295501ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:35.591679Z","caller":"traceutil/trace.go:171","msg":"trace[400014959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"103.426312ms","start":"2026-03-20T05:31:35.488237Z","end":"2026-03-20T05:31:35.591663Z","steps":["trace[400014959] 'agreement among raft nodes before linearized reading'  (duration: 103.27251ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.073719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.777141ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828087 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:580 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:31:36.073892Z","caller":"traceutil/trace.go:171","msg":"trace[1585958006] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"478.280845ms","start":"2026-03-20T05:31:35.595591Z","end":"2026-03-20T05:31:36.073872Z","steps":["trace[1585958006] 'process raft request'  (duration: 181.899042ms)","trace[1585958006] 'compare'  (duration: 295.049708ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:31:36.074014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:35.595575Z","time spent":"478.379997ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:580 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:31:36.074762Z","caller":"traceutil/trace.go:171","msg":"trace[765819555] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"478.999728ms","start":"2026-03-20T05:31:35.595714Z","end":"2026-03-20T05:31:36.074714Z","steps":["trace[765819555] 'process raft request'  (duration: 478.817115ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.074999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:35.595692Z","time spent":"479.180623ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:579 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:31:36.078636Z","caller":"traceutil/trace.go:171","msg":"trace[1067421878] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"258.877134ms","start":"2026-03-20T05:31:35.819733Z","end":"2026-03-20T05:31:36.07861Z","steps":["trace[1067421878] 'read index received'  (duration: 254.729457ms)","trace[1067421878] 'applied index is now lower than readState.Index'  (duration: 4.146617ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:31:36.078813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.050407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:31:36.078777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.005427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.07886Z","caller":"traceutil/trace.go:171","msg":"trace[447911895] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"259.1566ms","start":"2026-03-20T05:31:35.819692Z","end":"2026-03-20T05:31:36.078849Z","steps":["trace[447911895] 'agreement among raft nodes before linearized reading'  (duration: 259.059538ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:36.078894Z","caller":"traceutil/trace.go:171","msg":"trace[95884586] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"259.16232ms","start":"2026-03-20T05:31:35.819704Z","end":"2026-03-20T05:31:36.078866Z","steps":["trace[95884586] 'agreement among raft nodes before linearized reading'  (duration: 258.982547ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.07896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.378016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:31:36.078975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.331614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079016Z","caller":"traceutil/trace.go:171","msg":"trace[1591551280] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"207.452308ms","start":"2026-03-20T05:31:35.871549Z","end":"2026-03-20T05:31:36.079001Z","steps":["trace[1591551280] 'agreement among raft nodes before linearized reading'  (duration: 207.361616ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:36.079039Z","caller":"traceutil/trace.go:171","msg":"trace[1648861144] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"222.445867ms","start":"2026-03-20T05:31:35.856574Z","end":"2026-03-20T05:31:36.07902Z","steps":["trace[1648861144] 'agreement among raft nodes before linearized reading'  (duration: 222.319464ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.07911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.191742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.07916Z","caller":"traceutil/trace.go:171","msg":"trace[365658461] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"208.262973ms","start":"2026-03-20T05:31:35.870883Z","end":"2026-03-20T05:31:36.079146Z","steps":["trace[365658461] 'agreement among raft nodes before linearized reading'  (duration: 208.185252ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.384156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079297Z","caller":"traceutil/trace.go:171","msg":"trace[1320117162] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"208.435637ms","start":"2026-03-20T05:31:35.87085Z","end":"2026-03-20T05:31:36.079286Z","steps":["trace[1320117162] 'agreement among raft nodes before linearized reading'  (duration: 208.376175ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.683146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079375Z","caller":"traceutil/trace.go:171","msg":"trace[1367799189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"119.725077ms","start":"2026-03-20T05:31:35.95964Z","end":"2026-03-20T05:31:36.079366Z","steps":["trace[1367799189] 'agreement among raft nodes before linearized reading'  (duration: 119.648646ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.4361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079507Z","caller":"traceutil/trace.go:171","msg":"trace[659872207] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"241.479721ms","start":"2026-03-20T05:31:35.838003Z","end":"2026-03-20T05:31:36.079483Z","steps":["trace[659872207] 'agreement among raft nodes before linearized reading'  (duration: 241.382529ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.592499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079577Z","caller":"traceutil/trace.go:171","msg":"trace[1520128082] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"223.64758ms","start":"2026-03-20T05:31:35.855917Z","end":"2026-03-20T05:31:36.079565Z","steps":["trace[1520128082] 'agreement among raft nodes before linearized reading'  (duration: 223.573508ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.66115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:31:36.079703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.49468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079724Z","caller":"traceutil/trace.go:171","msg":"trace[1834092019] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"223.827383ms","start":"2026-03-20T05:31:35.855881Z","end":"2026-03-20T05:31:36.079709Z","steps":["trace[1834092019] 'agreement among raft nodes before linearized reading'  (duration: 223.63026ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:36.079741Z","caller":"traceutil/trace.go:171","msg":"trace[1610493770] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"242.54486ms","start":"2026-03-20T05:31:35.837183Z","end":"2026-03-20T05:31:36.079728Z","steps":["trace[1610493770] 'agreement among raft nodes before linearized reading'  (duration: 242.483599ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.079669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.356807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.079908Z","caller":"traceutil/trace.go:171","msg":"trace[625650810] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"242.642062ms","start":"2026-03-20T05:31:35.837251Z","end":"2026-03-20T05:31:36.079893Z","steps":["trace[625650810] 'agreement among raft nodes before linearized reading'  (duration: 242.385348ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:36.080274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.397945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:36.080316Z","caller":"traceutil/trace.go:171","msg":"trace[1248379335] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"259.834042ms","start":"2026-03-20T05:31:35.820472Z","end":"2026-03-20T05:31:36.080307Z","steps":["trace[1248379335] 'agreement among raft nodes before linearized reading'  (duration: 258.375625ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:38.805008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:38.372345Z","time spent":"432.657856ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-03-20T05:31:38.805146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.505417ms","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-03-20T05:31:38.805219Z","caller":"traceutil/trace.go:171","msg":"trace[322869774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"102.60072ms","start":"2026-03-20T05:31:38.702599Z","end":"2026-03-20T05:31:38.8052Z","steps":["trace[322869774] 'range keys from in-memory index tree'  (duration: 102.289112ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:38.805206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.247898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfdjz\" ","response":"range_response_count:1 size:16961"}
{"level":"info","ts":"2026-03-20T05:31:38.805277Z","caller":"traceutil/trace.go:171","msg":"trace[1549694931] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfdjz; range_end:; response_count:1; response_revision:595; }","duration":"483.36648ms","start":"2026-03-20T05:31:38.321892Z","end":"2026-03-20T05:31:38.805259Z","steps":["trace[1549694931] 'range keys from in-memory index tree'  (duration: 483.095006ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:38.805316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:38.321873Z","time spent":"483.433721ms","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-zfdjz\" "}
{"level":"warn","ts":"2026-03-20T05:31:38.805445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.9797ms","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-03-20T05:31:38.805512Z","caller":"traceutil/trace.go:171","msg":"trace[1424721831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"107.102953ms","start":"2026-03-20T05:31:38.698391Z","end":"2026-03-20T05:31:38.805494Z","steps":["trace[1424721831] 'range keys from in-memory index tree'  (duration: 106.791707ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:39.181415Z","caller":"traceutil/trace.go:171","msg":"trace[56198062] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"372.532138ms","start":"2026-03-20T05:31:38.808859Z","end":"2026-03-20T05:31:39.181391Z","steps":["trace[56198062] 'process raft request'  (duration: 372.382855ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:39.181571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:38.808837Z","time spent":"372.65583ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:588 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-20T05:31:39.303446Z","caller":"traceutil/trace.go:171","msg":"trace[2056263020] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"494.160059ms","start":"2026-03-20T05:31:38.809267Z","end":"2026-03-20T05:31:39.303427Z","steps":["trace[2056263020] 'process raft request'  (duration: 494.002697ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:39.303617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:38.809252Z","time spent":"494.255732ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:589 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-20T05:31:39.303668Z","caller":"traceutil/trace.go:171","msg":"trace[886761410] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"474.361492ms","start":"2026-03-20T05:31:38.829282Z","end":"2026-03-20T05:31:39.303643Z","steps":["trace[886761410] 'process raft request'  (duration: 474.104257ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:39.303834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:38.829262Z","time spent":"474.472074ms","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-zfdjz\" mod_revision:504 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-zfdjz\" value_size:17081 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-zfdjz\" > >"}
{"level":"info","ts":"2026-03-20T05:31:40.354534Z","caller":"traceutil/trace.go:171","msg":"trace[1365650249] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"256.545011ms","start":"2026-03-20T05:31:40.097964Z","end":"2026-03-20T05:31:40.354509Z","steps":["trace[1365650249] 'read index received'  (duration: 256.449179ms)","trace[1365650249] 'applied index is now lower than readState.Index'  (duration: 94.632µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:40.354615Z","caller":"traceutil/trace.go:171","msg":"trace[769766000] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"259.499865ms","start":"2026-03-20T05:31:40.095096Z","end":"2026-03-20T05:31:40.354596Z","steps":["trace[769766000] 'process raft request'  (duration: 259.260371ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:40.354805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.817715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-20T05:31:40.354885Z","caller":"traceutil/trace.go:171","msg":"trace[690710393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:600; }","duration":"256.939148ms","start":"2026-03-20T05:31:40.097928Z","end":"2026-03-20T05:31:40.354867Z","steps":["trace[690710393] 'agreement among raft nodes before linearized reading'  (duration: 256.688044ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.629871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.389177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-20T05:31:42.629958Z","caller":"traceutil/trace.go:171","msg":"trace[1506701213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"271.52145ms","start":"2026-03-20T05:31:42.358417Z","end":"2026-03-20T05:31:42.629939Z","steps":["trace[1506701213] 'range keys from in-memory index tree'  (duration: 271.296495ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.629965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.989128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-20T05:31:42.630061Z","caller":"traceutil/trace.go:171","msg":"trace[1018646506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"250.128901ms","start":"2026-03-20T05:31:42.379908Z","end":"2026-03-20T05:31:42.630037Z","steps":["trace[1018646506] 'range keys from in-memory index tree'  (duration: 249.870427ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.630263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669282784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:31:42.629936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144800467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:31:42.630305Z","caller":"traceutil/trace.go:171","msg":"trace[418842969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.669359526s","start":"2026-03-20T05:31:40.960933Z","end":"2026-03-20T05:31:42.630292Z","steps":["trace[418842969] 'range keys from in-memory index tree'  (duration: 1.669174212s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.630344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:40.960889Z","time spent":"1.669438838s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-20T05:31:42.630345Z","caller":"traceutil/trace.go:171","msg":"trace[1586489416] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:601; }","duration":"2.145211895s","start":"2026-03-20T05:31:40.485106Z","end":"2026-03-20T05:31:42.630318Z","steps":["trace[1586489416] 'range keys from in-memory index tree'  (duration: 2.144686115s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.630428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:40.48501Z","time spent":"2.145391699s","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "}
{"level":"warn","ts":"2026-03-20T05:31:42.630436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012349687s","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-03-20T05:31:42.630491Z","caller":"traceutil/trace.go:171","msg":"trace[233163596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.012440878s","start":"2026-03-20T05:31:41.618035Z","end":"2026-03-20T05:31:42.630476Z","steps":["trace[233163596] 'range keys from in-memory index tree'  (duration: 1.012150404s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.63054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:41.618015Z","time spent":"1.01251254s","remote":"127.0.0.1:54590","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-03-20T05:31:42.630564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323574616s","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-03-20T05:31:42.630592Z","caller":"traceutil/trace.go:171","msg":"trace[1387076530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"1.323642446s","start":"2026-03-20T05:31:41.306941Z","end":"2026-03-20T05:31:42.630584Z","steps":["trace[1387076530] 'range keys from in-memory index tree'  (duration: 1.323416432s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.630619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:41.306916Z","time spent":"1.323696758s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-20T05:31:42.630793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445574944s","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-03-20T05:31:42.630846Z","caller":"traceutil/trace.go:171","msg":"trace[1248068462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"1.445672426s","start":"2026-03-20T05:31:41.185164Z","end":"2026-03-20T05:31:42.630836Z","steps":["trace[1248068462] 'range keys from in-memory index tree'  (duration: 1.44540645s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:42.630886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:41.185132Z","time spent":"1.445734887s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-20T05:31:45.998101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.572308ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828176 > lease_revoke:<id:3e459d09b99f33a6>","response":"size:29"}
{"level":"warn","ts":"2026-03-20T05:31:47.196738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828177,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:31:47.270704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.479802ms","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-03-20T05:31:47.270794Z","caller":"traceutil/trace.go:171","msg":"trace[1683129068] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:629; }","duration":"525.579674ms","start":"2026-03-20T05:31:46.7452Z","end":"2026-03-20T05:31:47.27078Z","steps":["trace[1683129068] 'range keys from in-memory index tree'  (duration: 525.454962ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.282104Z","caller":"traceutil/trace.go:171","msg":"trace[396389645] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"586.10608ms","start":"2026-03-20T05:31:46.695986Z","end":"2026-03-20T05:31:47.282092Z","steps":["trace[396389645] 'read index received'  (duration: 585.991718ms)","trace[396389645] 'applied index is now lower than readState.Index'  (duration: 113.402µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:47.282149Z","caller":"traceutil/trace.go:171","msg":"trace[1573917290] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"586.940136ms","start":"2026-03-20T05:31:46.695173Z","end":"2026-03-20T05:31:47.282113Z","steps":["trace[1573917290] 'process raft request'  (duration: 586.775163ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:47.282254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.188223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-03-20T05:31:47.282255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.261913ms","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-03-20T05:31:47.282278Z","caller":"traceutil/trace.go:171","msg":"trace[2116389995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:630; }","duration":"585.279094ms","start":"2026-03-20T05:31:46.696992Z","end":"2026-03-20T05:31:47.282271Z","steps":["trace[2116389995] 'agreement among raft nodes before linearized reading'  (duration: 585.171372ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.282288Z","caller":"traceutil/trace.go:171","msg":"trace[284695409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"586.297154ms","start":"2026-03-20T05:31:46.695981Z","end":"2026-03-20T05:31:47.282278Z","steps":["trace[284695409] 'agreement among raft nodes before linearized reading'  (duration: 586.173571ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:47.282303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.696955Z","time spent":"585.341536ms","remote":"127.0.0.1:54590","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-03-20T05:31:47.282308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.695922Z","time spent":"586.380595ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-20T05:31:47.282313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.695151Z","time spent":"587.074558ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:587 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:47.282403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.477404ms","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-03-20T05:31:47.282429Z","caller":"traceutil/trace.go:171","msg":"trace[1006189520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"581.520254ms","start":"2026-03-20T05:31:46.700895Z","end":"2026-03-20T05:31:47.282415Z","steps":["trace[1006189520] 'agreement among raft nodes before linearized reading'  (duration: 581.469143ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:47.282396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.896504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-03-20T05:31:47.282427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.289882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-03-20T05:31:47.282461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.700883Z","time spent":"581.573895ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-20T05:31:47.282461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.511218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:47.282487Z","caller":"traceutil/trace.go:171","msg":"trace[1184676313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"322.570338ms","start":"2026-03-20T05:31:46.959911Z","end":"2026-03-20T05:31:47.282481Z","steps":["trace[1184676313] 'agreement among raft nodes before linearized reading'  (duration: 322.532037ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:47.282504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.959893Z","time spent":"322.607599ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-20T05:31:47.282495Z","caller":"traceutil/trace.go:171","msg":"trace[1825002964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"580.021636ms","start":"2026-03-20T05:31:46.702446Z","end":"2026-03-20T05:31:47.282467Z","steps":["trace[1825002964] 'agreement among raft nodes before linearized reading'  (duration: 579.779322ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.282494Z","caller":"traceutil/trace.go:171","msg":"trace[1908733782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"580.394273ms","start":"2026-03-20T05:31:46.702084Z","end":"2026-03-20T05:31:47.282478Z","steps":["trace[1908733782] 'agreement among raft nodes before linearized reading'  (duration: 580.182339ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:47.282539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.702073Z","time spent":"580.459274ms","remote":"127.0.0.1:54590","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-03-20T05:31:47.282541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.702438Z","time spent":"580.094738ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:31:47.450738Z","caller":"traceutil/trace.go:171","msg":"trace[746516523] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"164.794264ms","start":"2026-03-20T05:31:47.285922Z","end":"2026-03-20T05:31:47.450716Z","steps":["trace[746516523] 'process raft request'  (duration: 99.582272ms)","trace[746516523] 'compare'  (duration: 64.974328ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:47.460915Z","caller":"traceutil/trace.go:171","msg":"trace[1735432524] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"174.593767ms","start":"2026-03-20T05:31:47.286299Z","end":"2026-03-20T05:31:47.460893Z","steps":["trace[1735432524] 'process raft request'  (duration: 174.462374ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.461165Z","caller":"traceutil/trace.go:171","msg":"trace[1722054872] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"174.764971ms","start":"2026-03-20T05:31:47.28639Z","end":"2026-03-20T05:31:47.461155Z","steps":["trace[1722054872] 'process raft request'  (duration: 174.465305ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.461454Z","caller":"traceutil/trace.go:171","msg":"trace[1129190520] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"174.695319ms","start":"2026-03-20T05:31:47.286736Z","end":"2026-03-20T05:31:47.461431Z","steps":["trace[1129190520] 'process raft request'  (duration: 174.377354ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:47.461457Z","caller":"traceutil/trace.go:171","msg":"trace[712457974] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"173.816873ms","start":"2026-03-20T05:31:47.287618Z","end":"2026-03-20T05:31:47.461435Z","steps":["trace[712457974] 'process raft request'  (duration: 173.68397ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:48.733129Z","caller":"traceutil/trace.go:171","msg":"trace[1098732137] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"348.027492ms","start":"2026-03-20T05:31:48.385079Z","end":"2026-03-20T05:31:48.733106Z","steps":["trace[1098732137] 'process raft request'  (duration: 347.861569ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:48.733263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:48.385061Z","time spent":"348.133064ms","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18318,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-zfdjz\" mod_revision:636 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-zfdjz\" value_size:18269 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-zfdjz\" > >"}
{"level":"info","ts":"2026-03-20T05:31:49.69192Z","caller":"traceutil/trace.go:171","msg":"trace[772942406] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"165.01283ms","start":"2026-03-20T05:31:49.526889Z","end":"2026-03-20T05:31:49.691902Z","steps":["trace[772942406] 'process raft request'  (duration: 164.954008ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:49.692253Z","caller":"traceutil/trace.go:171","msg":"trace[1871334594] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"165.092581ms","start":"2026-03-20T05:31:49.527139Z","end":"2026-03-20T05:31:49.692231Z","steps":["trace[1871334594] 'process raft request'  (duration: 164.737435ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:49.692314Z","caller":"traceutil/trace.go:171","msg":"trace[1426201676] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"166.14504ms","start":"2026-03-20T05:31:49.526161Z","end":"2026-03-20T05:31:49.692306Z","steps":["trace[1426201676] 'process raft request'  (duration: 67.97687ms)","trace[1426201676] 'compare'  (duration: 97.546239ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:49.6925Z","caller":"traceutil/trace.go:171","msg":"trace[1847834919] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"131.904586ms","start":"2026-03-20T05:31:49.560559Z","end":"2026-03-20T05:31:49.692464Z","steps":["trace[1847834919] 'process raft request'  (duration: 131.60916ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:51.657493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407656869s","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828207 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/cilium-zfdjz.189e75b1cf1bf8a3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/cilium-zfdjz.189e75b1cf1bf8a3\" value_size:640 lease:4487165268839051202 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:31:51.657613Z","caller":"traceutil/trace.go:171","msg":"trace[1829238587] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"1.272213588s","start":"2026-03-20T05:31:50.385379Z","end":"2026-03-20T05:31:51.657593Z","steps":["trace[1829238587] 'read index received'  (duration: 56.771µs)","trace[1829238587] 'applied index is now lower than readState.Index'  (duration: 1.272154517s)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:51.657721Z","caller":"traceutil/trace.go:171","msg":"trace[2138871480] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.880702679s","start":"2026-03-20T05:31:49.777003Z","end":"2026-03-20T05:31:51.657706Z","steps":["trace[2138871480] 'process raft request'  (duration: 472.720474ms)","trace[2138871480] 'compare'  (duration: 1.406977336s)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:31:51.657789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:49.77698Z","time spent":"1.88077484s","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/cilium-zfdjz.189e75b1cf1bf8a3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/cilium-zfdjz.189e75b1cf1bf8a3\" value_size:640 lease:4487165268839051202 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:31:51.658013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172801113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:31:51.658082Z","caller":"traceutil/trace.go:171","msg":"trace[1496140175] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:645; }","duration":"1.172933166s","start":"2026-03-20T05:31:50.485132Z","end":"2026-03-20T05:31:51.658065Z","steps":["trace[1496140175] 'agreement among raft nodes before linearized reading'  (duration: 1.172711342s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:51.658122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:50.485121Z","time spent":"1.172993107s","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "}
{"level":"warn","ts":"2026-03-20T05:31:51.658184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272794058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfdjz\" ","response":"range_response_count:1 size:18333"}
{"level":"info","ts":"2026-03-20T05:31:51.658239Z","caller":"traceutil/trace.go:171","msg":"trace[1431395454] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfdjz; range_end:; response_count:1; response_revision:645; }","duration":"1.272907441s","start":"2026-03-20T05:31:50.385318Z","end":"2026-03-20T05:31:51.658226Z","steps":["trace[1431395454] 'agreement among raft nodes before linearized reading'  (duration: 1.272727097s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:51.658266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:50.385293Z","time spent":"1.272967601s","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18357,"request content":"key:\"/registry/pods/kube-system/cilium-zfdjz\" "}
{"level":"warn","ts":"2026-03-20T05:31:51.658344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.62932ms","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-03-20T05:31:51.658375Z","caller":"traceutil/trace.go:171","msg":"trace[1580219021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"132.68562ms","start":"2026-03-20T05:31:51.52568Z","end":"2026-03-20T05:31:51.658366Z","steps":["trace[1580219021] 'agreement among raft nodes before linearized reading'  (duration: 132.600509ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:51.658399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.902009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:51.658469Z","caller":"traceutil/trace.go:171","msg":"trace[378835383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"699.007991ms","start":"2026-03-20T05:31:50.959442Z","end":"2026-03-20T05:31:51.65845Z","steps":["trace[378835383] 'agreement among raft nodes before linearized reading'  (duration: 698.888198ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:51.658507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:50.959426Z","time spent":"699.073981ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:31:52.159762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828209,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:31:52.46956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.515545ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828211 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:3e459d09b99f3472>","response":"size:41"}
{"level":"warn","ts":"2026-03-20T05:31:52.469617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.659502Z","time spent":"810.111181ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-03-20T05:31:52.783404Z","caller":"traceutil/trace.go:171","msg":"trace[1482883716] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.120615737s","start":"2026-03-20T05:31:51.662752Z","end":"2026-03-20T05:31:52.783368Z","steps":["trace[1482883716] 'process raft request'  (duration: 1.120448664s)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:52.783467Z","caller":"traceutil/trace.go:171","msg":"trace[1634288729] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"1.123752494s","start":"2026-03-20T05:31:51.659688Z","end":"2026-03-20T05:31:52.783441Z","steps":["trace[1634288729] 'read index received'  (duration: 580.365879ms)","trace[1634288729] 'applied index is now lower than readState.Index'  (duration: 543.385415ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:52.783497Z","caller":"traceutil/trace.go:171","msg":"trace[566827752] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.12187186s","start":"2026-03-20T05:31:51.661581Z","end":"2026-03-20T05:31:52.783453Z","steps":["trace[566827752] 'process raft request'  (duration: 1.098804993s)","trace[566827752] 'compare'  (duration: 22.531308ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:52.783578Z","caller":"traceutil/trace.go:171","msg":"trace[749351409] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.113524045s","start":"2026-03-20T05:31:51.670043Z","end":"2026-03-20T05:31:52.783567Z","steps":["trace[749351409] 'process raft request'  (duration: 1.113262751s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:52.783676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.662739Z","time spent":"1.12079541s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:640 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:52.783697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.670036Z","time spent":"1.113568396s","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18502,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-zfdjz\" mod_revision:638 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-zfdjz\" value_size:18453 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-zfdjz\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:52.783703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.661565Z","time spent":"1.122022533s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:639 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:52.783931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124221492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:52.784013Z","caller":"traceutil/trace.go:171","msg":"trace[1988089444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.124352417s","start":"2026-03-20T05:31:51.659642Z","end":"2026-03-20T05:31:52.783994Z","steps":["trace[1988089444] 'agreement among raft nodes before linearized reading'  (duration: 1.124224464s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:52.784056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.659616Z","time spent":"1.124431619s","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:31:52.807901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11161441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-03-20T05:31:52.807945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11209622s","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-03-20T05:31:52.807994Z","caller":"traceutil/trace.go:171","msg":"trace[1409171582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.111730402s","start":"2026-03-20T05:31:51.696246Z","end":"2026-03-20T05:31:52.807977Z","steps":["trace[1409171582] 'agreement among raft nodes before linearized reading'  (duration: 1.111469117s)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:31:52.80801Z","caller":"traceutil/trace.go:171","msg":"trace[2002641292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.112238152s","start":"2026-03-20T05:31:51.695756Z","end":"2026-03-20T05:31:52.807995Z","steps":["trace[2002641292] 'agreement among raft nodes before linearized reading'  (duration: 1.111969416s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:52.808037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.696239Z","time spent":"1.111788813s","remote":"127.0.0.1:54590","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-03-20T05:31:52.808058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.695746Z","time spent":"1.112300733s","remote":"127.0.0.1:54590","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-03-20T05:31:52.80792Z","caller":"traceutil/trace.go:171","msg":"trace[781788185] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"337.061549ms","start":"2026-03-20T05:31:52.470836Z","end":"2026-03-20T05:31:52.807898Z","steps":["trace[781788185] 'process raft request'  (duration: 336.745702ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:52.808274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:52.470824Z","time spent":"337.392426ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:607 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487165268839052402 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:52.808098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112955225s","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-03-20T05:31:52.808344Z","caller":"traceutil/trace.go:171","msg":"trace[1234372308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.11321891s","start":"2026-03-20T05:31:51.69511Z","end":"2026-03-20T05:31:52.808329Z","steps":["trace[1234372308] 'agreement among raft nodes before linearized reading'  (duration: 1.112864763s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:52.80839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:51.6951Z","time spent":"1.113278961s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:31:59.178221Z","caller":"traceutil/trace.go:171","msg":"trace[177461938] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"372.416494ms","start":"2026-03-20T05:31:58.805785Z","end":"2026-03-20T05:31:59.178201Z","steps":["trace[177461938] 'process raft request'  (duration: 372.266362ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.178369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.805754Z","time spent":"372.542017ms","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-zfdjz\" mod_revision:666 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-zfdjz\" value_size:18645 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-zfdjz\" > >"}
{"level":"info","ts":"2026-03-20T05:31:59.30305Z","caller":"traceutil/trace.go:171","msg":"trace[289701364] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"495.361714ms","start":"2026-03-20T05:31:58.807663Z","end":"2026-03-20T05:31:59.303025Z","steps":["trace[289701364] 'process raft request'  (duration: 495.18791ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.303209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.807647Z","time spent":"495.481366ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:671 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-20T05:31:59.303305Z","caller":"traceutil/trace.go:171","msg":"trace[2080484680] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:704; }","duration":"449.834041ms","start":"2026-03-20T05:31:58.853447Z","end":"2026-03-20T05:31:59.303281Z","steps":["trace[2080484680] 'read index received'  (duration: 324.585988ms)","trace[2080484680] 'applied index is now lower than readState.Index'  (duration: 125.244073ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:31:59.303467Z","caller":"traceutil/trace.go:171","msg":"trace[2072311661] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"495.530757ms","start":"2026-03-20T05:31:58.807926Z","end":"2026-03-20T05:31:59.303456Z","steps":["trace[2072311661] 'process raft request'  (duration: 495.068459ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.303575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.807905Z","time spent":"495.586459ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:672 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:31:59.303758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.308979ms","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-03-20T05:31:59.30379Z","caller":"traceutil/trace.go:171","msg":"trace[787719830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"450.33808ms","start":"2026-03-20T05:31:58.853441Z","end":"2026-03-20T05:31:59.303779Z","steps":["trace[787719830] 'agreement among raft nodes before linearized reading'  (duration: 450.230058ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.303817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.853377Z","time spent":"450.433931ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-20T05:31:59.303945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.672079ms","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-03-20T05:31:59.303986Z","caller":"traceutil/trace.go:171","msg":"trace[1365251020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"413.754371ms","start":"2026-03-20T05:31:58.89022Z","end":"2026-03-20T05:31:59.303974Z","steps":["trace[1365251020] 'agreement among raft nodes before linearized reading'  (duration: 413.622279ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.304022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.890181Z","time spent":"413.832812ms","remote":"127.0.0.1:54590","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-03-20T05:31:59.304068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.911118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:59.3041Z","caller":"traceutil/trace.go:171","msg":"trace[641659227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"150.982689ms","start":"2026-03-20T05:31:59.153109Z","end":"2026-03-20T05:31:59.304091Z","steps":["trace[641659227] 'agreement among raft nodes before linearized reading'  (duration: 150.936408ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.304162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.379731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:31:59.304192Z","caller":"traceutil/trace.go:171","msg":"trace[1043465871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"346.440553ms","start":"2026-03-20T05:31:58.957743Z","end":"2026-03-20T05:31:59.304184Z","steps":["trace[1043465871] 'agreement among raft nodes before linearized reading'  (duration: 346.391602ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.304218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.957725Z","time spent":"346.487213ms","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:31:59.304268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.197107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfdjz\" ","response":"range_response_count:1 size:18709"}
{"level":"info","ts":"2026-03-20T05:31:59.304295Z","caller":"traceutil/trace.go:171","msg":"trace[1999409081] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfdjz; range_end:; response_count:1; response_revision:680; }","duration":"120.224798ms","start":"2026-03-20T05:31:59.184063Z","end":"2026-03-20T05:31:59.304288Z","steps":["trace[1999409081] 'agreement among raft nodes before linearized reading'  (duration: 120.144196ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.304395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.117899ms","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-03-20T05:31:59.304424Z","caller":"traceutil/trace.go:171","msg":"trace[1879497672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"414.175759ms","start":"2026-03-20T05:31:58.89024Z","end":"2026-03-20T05:31:59.304415Z","steps":["trace[1879497672] 'agreement among raft nodes before linearized reading'  (duration: 414.093217ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:31:59.304453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.890223Z","time spent":"414.224ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:32:05.450823Z","caller":"traceutil/trace.go:171","msg":"trace[1347493412] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"106.80217ms","start":"2026-03-20T05:32:05.343999Z","end":"2026-03-20T05:32:05.450801Z","steps":["trace[1347493412] 'process raft request'  (duration: 101.610214ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:08.013569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:08.514724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:09.015823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:09.516151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:09.960924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000568982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-03-20T05:32:09.960996Z","caller":"traceutil/trace.go:171","msg":"trace[423487145] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000690694s","start":"2026-03-20T05:32:07.960288Z","end":"2026-03-20T05:32:09.960979Z","steps":["trace[423487145] 'agreement among raft nodes before linearized reading'  (duration: 2.000597622s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:09.961049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:07.960271Z","time spent":"2.000770836s","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:32:10.016371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:10.516871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828426,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:10.539426Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.875990332s","expected-duration":"1s"}
{"level":"warn","ts":"2026-03-20T05:32:11.092098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.197308ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828427 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:756 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:32:11.092183Z","caller":"traceutil/trace.go:171","msg":"trace[110738588] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"3.578910036s","start":"2026-03-20T05:32:07.513258Z","end":"2026-03-20T05:32:11.092168Z","steps":["trace[110738588] 'process raft request'  (duration: 3.026564604s)","trace[110738588] 'compare'  (duration: 551.930943ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:11.092224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:07.513237Z","time spent":"3.578968827s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:756 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:32:11.174569Z","caller":"traceutil/trace.go:171","msg":"trace[1753015545] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:788; }","duration":"3.661202531s","start":"2026-03-20T05:32:07.513346Z","end":"2026-03-20T05:32:11.174549Z","steps":["trace[1753015545] 'read index received'  (duration: 3.026436503s)","trace[1753015545] 'applied index is now lower than readState.Index'  (duration: 634.764988ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:11.174838Z","caller":"traceutil/trace.go:171","msg":"trace[105485136] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"3.401992885s","start":"2026-03-20T05:32:07.772833Z","end":"2026-03-20T05:32:11.174826Z","steps":["trace[105485136] 'process raft request'  (duration: 3.401559287s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.174958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:07.772804Z","time spent":"3.402083267s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:677 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:11.175031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.661363073s","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-03-20T05:32:11.175096Z","caller":"traceutil/trace.go:171","msg":"trace[576398252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"3.661466756s","start":"2026-03-20T05:32:07.513614Z","end":"2026-03-20T05:32:11.175081Z","steps":["trace[576398252] 'agreement among raft nodes before linearized reading'  (duration: 3.661301193s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:07.513601Z","time spent":"3.661521587s","remote":"127.0.0.1:54590","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-03-20T05:32:11.175211Z","caller":"traceutil/trace.go:171","msg":"trace[2008984774] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.48375334s","start":"2026-03-20T05:32:09.69145Z","end":"2026-03-20T05:32:11.175204Z","steps":["trace[2008984774] 'process raft request'  (duration: 1.483048886s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:09.691423Z","time spent":"1.483808181s","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-7c96b6546b-5n95f.189e75b6720fe01d\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7c96b6546b-5n95f.189e75b6720fe01d\" value_size:723 lease:4487165268839052453 >> failure:<>"}
{"level":"warn","ts":"2026-03-20T05:32:11.175422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677438991s","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-03-20T05:32:11.17544Z","caller":"traceutil/trace.go:171","msg":"trace[1002592410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"1.677499542s","start":"2026-03-20T05:32:09.497935Z","end":"2026-03-20T05:32:11.175434Z","steps":["trace[1002592410] 'agreement among raft nodes before linearized reading'  (duration: 1.677442561s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.662092498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-03-20T05:32:11.175456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:09.497913Z","time spent":"1.677539692s","remote":"127.0.0.1:54590","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-03-20T05:32:11.175435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207135141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:11.175534Z","caller":"traceutil/trace.go:171","msg":"trace[1988615478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"1.207299854s","start":"2026-03-20T05:32:09.968214Z","end":"2026-03-20T05:32:11.175514Z","steps":["trace[1988615478] 'agreement among raft nodes before linearized reading'  (duration: 1.20709759s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.930293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"warn","ts":"2026-03-20T05:32:11.175581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:09.96819Z","time spent":"1.207381816s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-20T05:32:11.175593Z","caller":"traceutil/trace.go:171","msg":"trace[2097881291] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:763; }","duration":"688.955324ms","start":"2026-03-20T05:32:10.48663Z","end":"2026-03-20T05:32:11.175586Z","steps":["trace[2097881291] 'agreement among raft nodes before linearized reading'  (duration: 688.882342ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822907119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"warn","ts":"2026-03-20T05:32:11.175627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:10.486571Z","time spent":"689.049815ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "}
{"level":"info","ts":"2026-03-20T05:32:11.17548Z","caller":"traceutil/trace.go:171","msg":"trace[1015427961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"3.66216863s","start":"2026-03-20T05:32:07.5133Z","end":"2026-03-20T05:32:11.175469Z","steps":["trace[1015427961] 'agreement among raft nodes before linearized reading'  (duration: 3.662047228s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.17567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:07.513279Z","time spent":"3.662383953s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:32:11.175668Z","caller":"traceutil/trace.go:171","msg":"trace[1801561967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"1.82302364s","start":"2026-03-20T05:32:09.352628Z","end":"2026-03-20T05:32:11.175652Z","steps":["trace[1801561967] 'agreement among raft nodes before linearized reading'  (duration: 1.822828076s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.175711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:09.352609Z","time spent":"1.823095962s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-20T05:32:11.310787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.30121ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828435 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:3e459d09b99f3552>","response":"size:41"}
{"level":"info","ts":"2026-03-20T05:32:11.376822Z","caller":"traceutil/trace.go:171","msg":"trace[1346530370] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"196.877841ms","start":"2026-03-20T05:32:11.17992Z","end":"2026-03-20T05:32:11.376798Z","steps":["trace[1346530370] 'process raft request'  (duration: 196.652557ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:11.376894Z","caller":"traceutil/trace.go:171","msg":"trace[961282011] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"196.476163ms","start":"2026-03-20T05:32:11.180389Z","end":"2026-03-20T05:32:11.376865Z","steps":["trace[961282011] 'process raft request'  (duration: 196.371831ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:11.376926Z","caller":"traceutil/trace.go:171","msg":"trace[983848495] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"195.718768ms","start":"2026-03-20T05:32:11.181181Z","end":"2026-03-20T05:32:11.3769Z","steps":["trace[983848495] 'process raft request'  (duration: 195.614607ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:11.376955Z","caller":"traceutil/trace.go:171","msg":"trace[740928046] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"195.403073ms","start":"2026-03-20T05:32:11.181516Z","end":"2026-03-20T05:32:11.37692Z","steps":["trace[740928046] 'process raft request'  (duration: 195.309101ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:11.377229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.605699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:11.376881Z","caller":"traceutil/trace.go:171","msg":"trace[426140935] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:792; }","duration":"193.242803ms","start":"2026-03-20T05:32:11.183616Z","end":"2026-03-20T05:32:11.376859Z","steps":["trace[426140935] 'read index received'  (duration: 19.864158ms)","trace[426140935] 'applied index is now lower than readState.Index'  (duration: 173.377595ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:11.377276Z","caller":"traceutil/trace.go:171","msg":"trace[1225630794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"193.678921ms","start":"2026-03-20T05:32:11.183585Z","end":"2026-03-20T05:32:11.377264Z","steps":["trace[1225630794] 'agreement among raft nodes before linearized reading'  (duration: 193.60245ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:11.377522Z","caller":"traceutil/trace.go:171","msg":"trace[321425059] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"197.192696ms","start":"2026-03-20T05:32:11.180311Z","end":"2026-03-20T05:32:11.377504Z","steps":["trace[321425059] 'process raft request'  (duration: 196.392422ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:11.514345Z","caller":"traceutil/trace.go:171","msg":"trace[635457854] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"137.023441ms","start":"2026-03-20T05:32:11.377295Z","end":"2026-03-20T05:32:11.514318Z","steps":["trace[635457854] 'read index received'  (duration: 44.082208ms)","trace[635457854] 'applied index is now lower than readState.Index'  (duration: 92.938753ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:11.514366Z","caller":"traceutil/trace.go:171","msg":"trace[1213524632] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"202.640627ms","start":"2026-03-20T05:32:11.311693Z","end":"2026-03-20T05:32:11.514334Z","steps":["trace[1213524632] 'process raft request'  (duration: 109.726965ms)","trace[1213524632] 'compare'  (duration: 92.708299ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:11.514506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.093386ms","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-03-20T05:32:11.514542Z","caller":"traceutil/trace.go:171","msg":"trace[780964049] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:769; }","duration":"203.189727ms","start":"2026-03-20T05:32:11.311341Z","end":"2026-03-20T05:32:11.514531Z","steps":["trace[780964049] 'agreement among raft nodes before linearized reading'  (duration: 203.073975ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:13.728919Z","caller":"traceutil/trace.go:171","msg":"trace[348421440] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"343.416788ms","start":"2026-03-20T05:32:13.385476Z","end":"2026-03-20T05:32:13.728893Z","steps":["trace[348421440] 'process raft request'  (duration: 343.299706ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:13.729057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:13.385457Z","time spent":"343.513289ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:766 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-20T05:32:13.72914Z","caller":"traceutil/trace.go:171","msg":"trace[847875226] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:801; }","duration":"191.013642ms","start":"2026-03-20T05:32:13.538103Z","end":"2026-03-20T05:32:13.729116Z","steps":["trace[847875226] 'read index received'  (duration: 190.641745ms)","trace[847875226] 'applied index is now lower than readState.Index'  (duration: 371.207µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:13.729158Z","caller":"traceutil/trace.go:171","msg":"trace[179016668] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"343.580989ms","start":"2026-03-20T05:32:13.385566Z","end":"2026-03-20T05:32:13.729147Z","steps":["trace[179016668] 'process raft request'  (duration: 343.301344ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:13.729219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:13.38556Z","time spent":"343.62637ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:765 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:13.729262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.151444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-20T05:32:13.729304Z","caller":"traceutil/trace.go:171","msg":"trace[2089695325] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:774; }","duration":"191.227206ms","start":"2026-03-20T05:32:13.538067Z","end":"2026-03-20T05:32:13.729294Z","steps":["trace[2089695325] 'agreement among raft nodes before linearized reading'  (duration: 191.113684ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:13.729289Z","caller":"traceutil/trace.go:171","msg":"trace[119605319] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"340.098666ms","start":"2026-03-20T05:32:13.38916Z","end":"2026-03-20T05:32:13.729258Z","steps":["trace[119605319] 'process raft request'  (duration: 339.907673ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:13.729437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:13.38914Z","time spent":"340.217238ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:768 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:32:19.904765Z","caller":"traceutil/trace.go:171","msg":"trace[425984114] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"116.932929ms","start":"2026-03-20T05:32:19.78781Z","end":"2026-03-20T05:32:19.904742Z","steps":["trace[425984114] 'process raft request'  (duration: 116.851337ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:19.904798Z","caller":"traceutil/trace.go:171","msg":"trace[1849795422] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"116.947479ms","start":"2026-03-20T05:32:19.787813Z","end":"2026-03-20T05:32:19.90476Z","steps":["trace[1849795422] 'process raft request'  (duration: 116.133313ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:20.880452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.691057ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828519 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:769 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487165268839052709 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:32:20.880699Z","caller":"traceutil/trace.go:171","msg":"trace[527011617] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"377.323206ms","start":"2026-03-20T05:32:20.503352Z","end":"2026-03-20T05:32:20.880675Z","steps":["trace[527011617] 'process raft request'  (duration: 51.265241ms)","trace[527011617] 'compare'  (duration: 325.413432ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:20.880831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:20.503334Z","time spent":"377.425788ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:769 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487165268839052709 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:20.880951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.489221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-20T05:32:20.881015Z","caller":"traceutil/trace.go:171","msg":"trace[27822253] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:796; }","duration":"252.614304ms","start":"2026-03-20T05:32:20.628387Z","end":"2026-03-20T05:32:20.881002Z","steps":["trace[27822253] 'agreement among raft nodes before linearized reading'  (duration: 252.364509ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:20.881178Z","caller":"traceutil/trace.go:171","msg":"trace[358933424] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"252.174335ms","start":"2026-03-20T05:32:20.628441Z","end":"2026-03-20T05:32:20.880615Z","steps":["trace[358933424] 'read index received'  (duration: 34.63µs)","trace[358933424] 'applied index is now lower than readState.Index'  (duration: 252.137815ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:21.427886Z","caller":"traceutil/trace.go:171","msg":"trace[2105090256] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"155.597624ms","start":"2026-03-20T05:32:21.272263Z","end":"2026-03-20T05:32:21.42786Z","steps":["trace[2105090256] 'process raft request'  (duration: 155.428611ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:21.487691Z","caller":"traceutil/trace.go:171","msg":"trace[421023941] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"210.219017ms","start":"2026-03-20T05:32:21.277445Z","end":"2026-03-20T05:32:21.487664Z","steps":["trace[421023941] 'process raft request'  (duration: 201.369393ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:21.695472Z","caller":"traceutil/trace.go:171","msg":"trace[1477918732] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"132.941324ms","start":"2026-03-20T05:32:21.562508Z","end":"2026-03-20T05:32:21.695449Z","steps":["trace[1477918732] 'process raft request'  (duration: 68.936928ms)","trace[1477918732] 'compare'  (duration: 63.905365ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:21.951015Z","caller":"traceutil/trace.go:171","msg":"trace[1680946536] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"111.683341ms","start":"2026-03-20T05:32:21.839306Z","end":"2026-03-20T05:32:21.95099Z","steps":["trace[1680946536] 'process raft request'  (duration: 111.391295ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:24.072217Z","caller":"traceutil/trace.go:171","msg":"trace[1721900323] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"369.296597ms","start":"2026-03-20T05:32:23.702903Z","end":"2026-03-20T05:32:24.0722Z","steps":["trace[1721900323] 'process raft request'  (duration: 369.194085ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:24.072332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:23.702883Z","time spent":"369.388108ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:799 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-20T05:32:24.129369Z","caller":"traceutil/trace.go:171","msg":"trace[402243964] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"170.310788ms","start":"2026-03-20T05:32:23.959035Z","end":"2026-03-20T05:32:24.129346Z","steps":["trace[402243964] 'read index received'  (duration: 113.161908ms)","trace[402243964] 'applied index is now lower than readState.Index'  (duration: 57.1481ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:24.129472Z","caller":"traceutil/trace.go:171","msg":"trace[313949630] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"275.989757ms","start":"2026-03-20T05:32:23.853462Z","end":"2026-03-20T05:32:24.129452Z","steps":["trace[313949630] 'process raft request'  (duration: 275.756343ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:24.129548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.055203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:24.129601Z","caller":"traceutil/trace.go:171","msg":"trace[1768943441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"170.166824ms","start":"2026-03-20T05:32:23.959424Z","end":"2026-03-20T05:32:24.129591Z","steps":["trace[1768943441] 'agreement among raft nodes before linearized reading'  (duration: 170.052782ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:24.129606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.562333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-03-20T05:32:24.129636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.384834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-03-20T05:32:24.129673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.440975ms","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-03-20T05:32:24.129682Z","caller":"traceutil/trace.go:171","msg":"trace[540043243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"170.684425ms","start":"2026-03-20T05:32:23.958981Z","end":"2026-03-20T05:32:24.129666Z","steps":["trace[540043243] 'agreement among raft nodes before linearized reading'  (duration: 170.480251ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:24.129688Z","caller":"traceutil/trace.go:171","msg":"trace[1352201729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"153.461815ms","start":"2026-03-20T05:32:23.976213Z","end":"2026-03-20T05:32:24.129674Z","steps":["trace[1352201729] 'agreement among raft nodes before linearized reading'  (duration: 153.284422ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:24.129725Z","caller":"traceutil/trace.go:171","msg":"trace[1789853378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"160.486215ms","start":"2026-03-20T05:32:23.96922Z","end":"2026-03-20T05:32:24.129706Z","steps":["trace[1789853378] 'agreement among raft nodes before linearized reading'  (duration: 160.339113ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:25.587779Z","caller":"traceutil/trace.go:171","msg":"trace[548097274] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"132.883664ms","start":"2026-03-20T05:32:25.45487Z","end":"2026-03-20T05:32:25.587754Z","steps":["trace[548097274] 'read index received'  (duration: 132.710219ms)","trace[548097274] 'applied index is now lower than readState.Index'  (duration: 172.525µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:25.587911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.019836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:25.587944Z","caller":"traceutil/trace.go:171","msg":"trace[1037240814] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:809; }","duration":"133.118918ms","start":"2026-03-20T05:32:25.454815Z","end":"2026-03-20T05:32:25.587934Z","steps":["trace[1037240814] 'agreement among raft nodes before linearized reading'  (duration: 133.039146ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:25.588173Z","caller":"traceutil/trace.go:171","msg":"trace[1720683599] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"134.738158ms","start":"2026-03-20T05:32:25.453424Z","end":"2026-03-20T05:32:25.588162Z","steps":["trace[1720683599] 'process raft request'  (duration: 134.201137ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:26.008774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.692541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-20T05:32:26.00892Z","caller":"traceutil/trace.go:171","msg":"trace[1205879073] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:809; }","duration":"132.893354ms","start":"2026-03-20T05:32:25.876005Z","end":"2026-03-20T05:32:26.008899Z","steps":["trace[1205879073] 'count revisions from in-memory index tree'  (duration: 132.605308ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:26.616383Z","caller":"traceutil/trace.go:171","msg":"trace[314364132] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"536.645429ms","start":"2026-03-20T05:32:26.079717Z","end":"2026-03-20T05:32:26.616362Z","steps":["trace[314364132] 'process raft request'  (duration: 536.374635ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:26.616546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:26.07969Z","time spent":"536.763891ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:804 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-20T05:32:26.616294Z","caller":"traceutil/trace.go:171","msg":"trace[2092213782] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"425.921397ms","start":"2026-03-20T05:32:26.190348Z","end":"2026-03-20T05:32:26.616269Z","steps":["trace[2092213782] 'read index received'  (duration: 425.802485ms)","trace[2092213782] 'applied index is now lower than readState.Index'  (duration: 117.582µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:26.616834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.452157ms","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-03-20T05:32:26.617082Z","caller":"traceutil/trace.go:171","msg":"trace[2144287803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"426.714142ms","start":"2026-03-20T05:32:26.1903Z","end":"2026-03-20T05:32:26.617014Z","steps":["trace[2144287803] 'agreement among raft nodes before linearized reading'  (duration: 426.258923ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:26.617317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:26.190283Z","time spent":"427.017567ms","remote":"127.0.0.1:54590","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-03-20T05:32:27.117023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828565,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:27.46839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.678199ms","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-03-20T05:32:27.469558Z","caller":"traceutil/trace.go:171","msg":"trace[988473012] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:810; }","duration":"723.896961ms","start":"2026-03-20T05:32:26.745628Z","end":"2026-03-20T05:32:27.469525Z","steps":["trace[988473012] 'range keys from in-memory index tree'  (duration: 722.656048ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:27.674903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.878405ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828564 > lease_revoke:<id:3e459d09b99f3552>","response":"size:29"}
{"level":"info","ts":"2026-03-20T05:32:27.675244Z","caller":"traceutil/trace.go:171","msg":"trace[1883781710] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:843; }","duration":"1.058300212s","start":"2026-03-20T05:32:26.616919Z","end":"2026-03-20T05:32:27.67522Z","steps":["trace[1883781710] 'read index received'  (duration: 604.264064ms)","trace[1883781710] 'applied index is now lower than readState.Index'  (duration: 454.034847ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:27.675325Z","caller":"traceutil/trace.go:171","msg":"trace[219550764] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.054101414s","start":"2026-03-20T05:32:26.621189Z","end":"2026-03-20T05:32:27.675291Z","steps":["trace[219550764] 'process raft request'  (duration: 1.053860818s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:27.675496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:26.621173Z","time spent":"1.054233337s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:806 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:27.676099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479643314s","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-03-20T05:32:27.676154Z","caller":"traceutil/trace.go:171","msg":"trace[1845223107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"1.479721045s","start":"2026-03-20T05:32:26.196416Z","end":"2026-03-20T05:32:27.676137Z","steps":["trace[1845223107] 'agreement among raft nodes before linearized reading'  (duration: 1.478932491s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:27.676187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:26.196408Z","time spent":"1.479769387s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-20T05:32:27.676499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480515839s","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-03-20T05:32:27.676651Z","caller":"traceutil/trace.go:171","msg":"trace[1622569207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"1.481230583s","start":"2026-03-20T05:32:26.195395Z","end":"2026-03-20T05:32:27.676626Z","steps":["trace[1622569207] 'agreement among raft nodes before linearized reading'  (duration: 1.480345947s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:27.676742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:26.195383Z","time spent":"1.481344465s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-03-20T05:32:27.792251Z","caller":"traceutil/trace.go:171","msg":"trace[213921681] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"110.5657ms","start":"2026-03-20T05:32:27.681661Z","end":"2026-03-20T05:32:27.792227Z","steps":["trace[213921681] 'process raft request'  (duration: 110.477589ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:27.792466Z","caller":"traceutil/trace.go:171","msg":"trace[874428310] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"110.07502ms","start":"2026-03-20T05:32:27.682369Z","end":"2026-03-20T05:32:27.792444Z","steps":["trace[874428310] 'process raft request'  (duration: 109.811735ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:27.792688Z","caller":"traceutil/trace.go:171","msg":"trace[154412977] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"112.127319ms","start":"2026-03-20T05:32:27.680532Z","end":"2026-03-20T05:32:27.79266Z","steps":["trace[154412977] 'process raft request'  (duration: 107.154307ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:37.873515Z","caller":"traceutil/trace.go:171","msg":"trace[2034992112] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"157.099302ms","start":"2026-03-20T05:32:37.716389Z","end":"2026-03-20T05:32:37.873488Z","steps":["trace[2034992112] 'process raft request'  (duration: 156.943359ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.43236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828757,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-20T05:32:40.839816Z","caller":"traceutil/trace.go:171","msg":"trace[402912944] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"959.704985ms","start":"2026-03-20T05:32:39.880087Z","end":"2026-03-20T05:32:40.839792Z","steps":["trace[402912944] 'process raft request'  (duration: 959.569022ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.839962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:39.880062Z","time spent":"959.826496ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:890 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:32:40.840422Z","caller":"traceutil/trace.go:171","msg":"trace[2108498838] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:932; }","duration":"908.81716ms","start":"2026-03-20T05:32:39.931592Z","end":"2026-03-20T05:32:40.840409Z","steps":["trace[2108498838] 'read index received'  (duration: 908.813259ms)","trace[2108498838] 'applied index is now lower than readState.Index'  (duration: 3.061µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:40.840566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.364253ms","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-03-20T05:32:40.840601Z","caller":"traceutil/trace.go:171","msg":"trace[981644599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"907.410474ms","start":"2026-03-20T05:32:39.933182Z","end":"2026-03-20T05:32:40.840592Z","steps":["trace[981644599] 'agreement among raft nodes before linearized reading'  (duration: 907.297702ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.840644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:39.933178Z","time spent":"907.457325ms","remote":"127.0.0.1:54590","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-03-20T05:32:40.840829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.233448ms","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-03-20T05:32:40.840855Z","caller":"traceutil/trace.go:171","msg":"trace[952298666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"909.283399ms","start":"2026-03-20T05:32:39.931564Z","end":"2026-03-20T05:32:40.840847Z","steps":["trace[952298666] 'agreement among raft nodes before linearized reading'  (duration: 909.205488ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.84088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:39.931549Z","time spent":"909.32527ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-20T05:32:40.840885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.18559ms","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-03-20T05:32:40.840914Z","caller":"traceutil/trace.go:171","msg":"trace[86178609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"907.254901ms","start":"2026-03-20T05:32:39.933652Z","end":"2026-03-20T05:32:40.840907Z","steps":["trace[86178609] 'agreement among raft nodes before linearized reading'  (duration: 907.17499ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.840935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:39.933632Z","time spent":"907.297252ms","remote":"127.0.0.1:54590","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-03-20T05:32:40.841067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.97741ms","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-03-20T05:32:40.841092Z","caller":"traceutil/trace.go:171","msg":"trace[778171211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"123.035782ms","start":"2026-03-20T05:32:40.718048Z","end":"2026-03-20T05:32:40.841084Z","steps":["trace[778171211] 'agreement among raft nodes before linearized reading'  (duration: 122.94393ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.841214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.612338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:32:40.841231Z","caller":"traceutil/trace.go:171","msg":"trace[1211975343] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:895; }","duration":"352.676549ms","start":"2026-03-20T05:32:40.48855Z","end":"2026-03-20T05:32:40.841227Z","steps":["trace[1211975343] 'agreement among raft nodes before linearized reading'  (duration: 352.622468ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:40.841248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.48853Z","time spent":"352.713529ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "}
{"level":"warn","ts":"2026-03-20T05:32:41.460576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828760,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-20T05:32:41.863876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.357349ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828762 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:3e459d09b99f3699>","response":"size:41"}
{"level":"warn","ts":"2026-03-20T05:32:41.863935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.84598Z","time spent":"1.017951073s","remote":"127.0.0.1:54352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-03-20T05:32:41.892679Z","caller":"traceutil/trace.go:171","msg":"trace[411301191] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.043367504s","start":"2026-03-20T05:32:40.849288Z","end":"2026-03-20T05:32:41.892655Z","steps":["trace[411301191] 'process raft request'  (duration: 1.043299863s)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:41.89288Z","caller":"traceutil/trace.go:171","msg":"trace[567300807] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:932; }","duration":"933.237713ms","start":"2026-03-20T05:32:40.959621Z","end":"2026-03-20T05:32:41.892858Z","steps":["trace[567300807] 'read index received'  (duration: 637.904027ms)","trace[567300807] 'applied index is now lower than readState.Index'  (duration: 295.332466ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:32:41.892873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.849283Z","time spent":"1.043471406s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:894 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:41.892998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.383416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:41.893069Z","caller":"traceutil/trace.go:171","msg":"trace[45648102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"933.471688ms","start":"2026-03-20T05:32:40.959553Z","end":"2026-03-20T05:32:41.893024Z","steps":["trace[45648102] 'agreement among raft nodes before linearized reading'  (duration: 933.381686ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:41.893106Z","caller":"traceutil/trace.go:171","msg":"trace[244215054] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.043882654s","start":"2026-03-20T05:32:40.849211Z","end":"2026-03-20T05:32:41.893094Z","steps":["trace[244215054] 'process raft request'  (duration: 1.043157211s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:41.893141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.959495Z","time spent":"933.61687ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:32:41.893198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.849188Z","time spent":"1.043977055s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:892 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:32:41.893261Z","caller":"traceutil/trace.go:171","msg":"trace[1884246493] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.043921975s","start":"2026-03-20T05:32:40.84932Z","end":"2026-03-20T05:32:41.893242Z","steps":["trace[1884246493] 'process raft request'  (duration: 1.043299743s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:41.893369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.849315Z","time spent":"1.043997076s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:891 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-20T05:32:41.893379Z","caller":"traceutil/trace.go:171","msg":"trace[545207133] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.044108767s","start":"2026-03-20T05:32:40.849236Z","end":"2026-03-20T05:32:41.893345Z","steps":["trace[545207133] 'process raft request'  (duration: 1.043311803s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:41.893475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:40.849221Z","time spent":"1.044210859s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:893 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:51.106782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.658086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:32:51.106861Z","caller":"traceutil/trace.go:171","msg":"trace[1384008407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"148.797799ms","start":"2026-03-20T05:32:50.958048Z","end":"2026-03-20T05:32:51.106845Z","steps":["trace[1384008407] 'range keys from in-memory index tree'  (duration: 148.549214ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:54.370295Z","caller":"traceutil/trace.go:171","msg":"trace[2096184312] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"246.731756ms","start":"2026-03-20T05:32:54.123538Z","end":"2026-03-20T05:32:54.37027Z","steps":["trace[2096184312] 'process raft request'  (duration: 246.024853ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:54.380573Z","caller":"traceutil/trace.go:171","msg":"trace[1884409692] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"251.714439ms","start":"2026-03-20T05:32:54.128826Z","end":"2026-03-20T05:32:54.380541Z","steps":["trace[1884409692] 'read index received'  (duration: 240.749775ms)","trace[1884409692] 'applied index is now lower than readState.Index'  (duration: 10.962844ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:54.380653Z","caller":"traceutil/trace.go:171","msg":"trace[1146399234] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"255.870085ms","start":"2026-03-20T05:32:54.124754Z","end":"2026-03-20T05:32:54.380624Z","steps":["trace[1146399234] 'process raft request'  (duration: 255.646801ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:54.38084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.981753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-20T05:32:54.380914Z","caller":"traceutil/trace.go:171","msg":"trace[63085251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"252.085745ms","start":"2026-03-20T05:32:54.128808Z","end":"2026-03-20T05:32:54.380894Z","steps":["trace[63085251] 'agreement among raft nodes before linearized reading'  (duration: 251.867511ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:55.088547Z","caller":"traceutil/trace.go:171","msg":"trace[996822470] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"136.974591ms","start":"2026-03-20T05:32:54.951541Z","end":"2026-03-20T05:32:55.088516Z","steps":["trace[996822470] 'process raft request'  (duration: 136.783736ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:56.495245Z","caller":"traceutil/trace.go:171","msg":"trace[24658497] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"100.276079ms","start":"2026-03-20T05:32:56.394945Z","end":"2026-03-20T05:32:56.495221Z","steps":["trace[24658497] 'process raft request'  (duration: 100.133096ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:58.771655Z","caller":"traceutil/trace.go:171","msg":"trace[1884120341] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"102.278946ms","start":"2026-03-20T05:32:58.669358Z","end":"2026-03-20T05:32:58.771637Z","steps":["trace[1884120341] 'process raft request'  (duration: 102.163464ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:59.306116Z","caller":"traceutil/trace.go:171","msg":"trace[585201012] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"520.189527ms","start":"2026-03-20T05:32:58.785909Z","end":"2026-03-20T05:32:59.306099Z","steps":["trace[585201012] 'process raft request'  (duration: 520.073165ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:59.306238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.785889Z","time spent":"520.285569ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" mod_revision:880 > success:<request_put:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" value_size:829 >> failure:<request_range:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" > >"}
{"level":"info","ts":"2026-03-20T05:32:59.74112Z","caller":"traceutil/trace.go:171","msg":"trace[499613808] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"955.091232ms","start":"2026-03-20T05:32:58.785999Z","end":"2026-03-20T05:32:59.74109Z","steps":["trace[499613808] 'process raft request'  (duration: 954.762286ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:59.741286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.785973Z","time spent":"955.224735ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" mod_revision:873 > success:<request_put:<key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" value_size:9311 >> failure:<request_range:<key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" > >"}
{"level":"info","ts":"2026-03-20T05:32:59.74133Z","caller":"traceutil/trace.go:171","msg":"trace[1942297962] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1004; }","duration":"928.629432ms","start":"2026-03-20T05:32:58.812676Z","end":"2026-03-20T05:32:59.741305Z","steps":["trace[1942297962] 'read index received'  (duration: 493.37207ms)","trace[1942297962] 'applied index is now lower than readState.Index'  (duration: 435.256482ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:32:59.741509Z","caller":"traceutil/trace.go:171","msg":"trace[1767669465] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"955.167594ms","start":"2026-03-20T05:32:58.786325Z","end":"2026-03-20T05:32:59.741492Z","steps":["trace[1767669465] 'process raft request'  (duration: 954.598843ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:59.741799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.520773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:32:59.741588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.911367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-03-20T05:32:59.741821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.786301Z","time spent":"955.422628ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-nbn8v\" mod_revision:881 > success:<request_put:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-nbn8v\" value_size:1420 >> failure:<request_range:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-nbn8v\" > >"}
{"level":"warn","ts":"2026-03-20T05:32:59.741867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.669243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-20T05:32:59.741889Z","caller":"traceutil/trace.go:171","msg":"trace[529291082] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:965; }","duration":"929.214003ms","start":"2026-03-20T05:32:58.812632Z","end":"2026-03-20T05:32:59.741846Z","steps":["trace[529291082] 'agreement among raft nodes before linearized reading'  (duration: 928.896567ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:32:59.741907Z","caller":"traceutil/trace.go:171","msg":"trace[1413709180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"639.746295ms","start":"2026-03-20T05:32:59.102147Z","end":"2026-03-20T05:32:59.741893Z","steps":["trace[1413709180] 'agreement among raft nodes before linearized reading'  (duration: 639.612973ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:59.741939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:59.102127Z","time spent":"639.805976ms","remote":"127.0.0.1:54590","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-03-20T05:32:59.741937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.812618Z","time spent":"929.302634ms","remote":"127.0.0.1:54816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "}
{"level":"info","ts":"2026-03-20T05:32:59.742525Z","caller":"traceutil/trace.go:171","msg":"trace[840370883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"782.322649ms","start":"2026-03-20T05:32:58.96019Z","end":"2026-03-20T05:32:59.742513Z","steps":["trace[840370883] 'agreement among raft nodes before linearized reading'  (duration: 781.530594ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:32:59.742582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.960172Z","time spent":"782.39483ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:33:00.705536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.264514ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828928 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:949 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:33:00.705646Z","caller":"traceutil/trace.go:171","msg":"trace[1091387899] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"564.209313ms","start":"2026-03-20T05:33:00.141417Z","end":"2026-03-20T05:33:00.705627Z","steps":["trace[1091387899] 'read index received'  (duration: 50.771µs)","trace[1091387899] 'applied index is now lower than readState.Index'  (duration: 564.157232ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:00.705891Z","caller":"traceutil/trace.go:171","msg":"trace[2039122780] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"959.24743ms","start":"2026-03-20T05:32:59.74663Z","end":"2026-03-20T05:33:00.705877Z","steps":["trace[2039122780] 'process raft request'  (duration: 126.540538ms)","trace[2039122780] 'compare'  (duration: 831.844306ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:00.705964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:59.746618Z","time spent":"959.310501ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:949 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:00.706106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.6936ms","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-03-20T05:33:00.706194Z","caller":"traceutil/trace.go:171","msg":"trace[823305600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"305.841233ms","start":"2026-03-20T05:33:00.400332Z","end":"2026-03-20T05:33:00.706173Z","steps":["trace[823305600] 'agreement among raft nodes before linearized reading'  (duration: 305.643219ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:00.706234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.400309Z","time spent":"305.916824ms","remote":"127.0.0.1:54590","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-03-20T05:33:00.706157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.739584ms","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-03-20T05:33:00.706361Z","caller":"traceutil/trace.go:171","msg":"trace[602406872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"564.988378ms","start":"2026-03-20T05:33:00.141358Z","end":"2026-03-20T05:33:00.706346Z","steps":["trace[602406872] 'agreement among raft nodes before linearized reading'  (duration: 564.701213ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:00.706486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.141334Z","time spent":"565.125321ms","remote":"127.0.0.1:54590","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-03-20T05:33:00.706275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.442224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:33:00.706812Z","caller":"traceutil/trace.go:171","msg":"trace[606260827] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:966; }","duration":"217.016785ms","start":"2026-03-20T05:33:00.489779Z","end":"2026-03-20T05:33:00.706796Z","steps":["trace[606260827] 'agreement among raft nodes before linearized reading'  (duration: 216.324392ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:00.706158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.664043ms","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-03-20T05:33:00.707315Z","caller":"traceutil/trace.go:171","msg":"trace[767857980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"200.851216ms","start":"2026-03-20T05:33:00.506449Z","end":"2026-03-20T05:33:00.7073Z","steps":["trace[767857980] 'agreement among raft nodes before linearized reading'  (duration: 199.613323ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:00.707647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.85378ms","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-03-20T05:33:00.70769Z","caller":"traceutil/trace.go:171","msg":"trace[1897752436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"197.910961ms","start":"2026-03-20T05:33:00.509769Z","end":"2026-03-20T05:33:00.70768Z","steps":["trace[1897752436] 'agreement among raft nodes before linearized reading'  (duration: 196.221839ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:01.447419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.708549Z","time spent":"738.666039ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-03-20T05:33:01.686771Z","caller":"traceutil/trace.go:171","msg":"trace[21320237] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"975.543311ms","start":"2026-03-20T05:33:00.711208Z","end":"2026-03-20T05:33:01.686752Z","steps":["trace[21320237] 'process raft request'  (duration: 975.48247ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:01.686823Z","caller":"traceutil/trace.go:171","msg":"trace[1285339867] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"976.502369ms","start":"2026-03-20T05:33:00.710286Z","end":"2026-03-20T05:33:01.686789Z","steps":["trace[1285339867] 'process raft request'  (duration: 952.477683ms)","trace[1285339867] 'compare'  (duration: 23.810772ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:01.686894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.711199Z","time spent":"975.630704ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:956 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-20T05:33:01.686922Z","caller":"traceutil/trace.go:171","msg":"trace[1276213758] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"975.332837ms","start":"2026-03-20T05:33:00.71158Z","end":"2026-03-20T05:33:01.686913Z","steps":["trace[1276213758] 'process raft request'  (duration: 975.145624ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:01.686966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.711555Z","time spent":"975.389228ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:961 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:01.686998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.710262Z","time spent":"976.650041ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:959 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:33:01.687023Z","caller":"traceutil/trace.go:171","msg":"trace[1400916250] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"974.667135ms","start":"2026-03-20T05:33:00.712351Z","end":"2026-03-20T05:33:01.687018Z","steps":["trace[1400916250] 'process raft request'  (duration: 974.529592ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:01.687011Z","caller":"traceutil/trace.go:171","msg":"trace[1834939469] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1008; }","duration":"726.472172ms","start":"2026-03-20T05:33:00.960521Z","end":"2026-03-20T05:33:01.686993Z","steps":["trace[1834939469] 'read index received'  (duration: 475.677822ms)","trace[1834939469] 'applied index is now lower than readState.Index'  (duration: 250.79353ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:01.687053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.712336Z","time spent":"974.700265ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:960 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:01.687088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.556104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:01.687112Z","caller":"traceutil/trace.go:171","msg":"trace[536938807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"726.589914ms","start":"2026-03-20T05:33:00.960513Z","end":"2026-03-20T05:33:01.687103Z","steps":["trace[536938807] 'agreement among raft nodes before linearized reading'  (duration: 726.541174ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:01.687133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.960441Z","time spent":"726.687777ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:33:02.183394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.480094ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828937 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:923 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487165268839053123 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:33:02.183519Z","caller":"traceutil/trace.go:171","msg":"trace[2105549601] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"495.544959ms","start":"2026-03-20T05:33:01.687955Z","end":"2026-03-20T05:33:02.1835Z","steps":["trace[2105549601] 'read index received'  (duration: 209.77827ms)","trace[2105549601] 'applied index is now lower than readState.Index'  (duration: 285.765259ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:02.183632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.671992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:02.183658Z","caller":"traceutil/trace.go:171","msg":"trace[1540165154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"495.728663ms","start":"2026-03-20T05:33:01.687921Z","end":"2026-03-20T05:33:02.183649Z","steps":["trace[1540165154] 'agreement among raft nodes before linearized reading'  (duration: 495.645722ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:02.18371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:01.687906Z","time spent":"495.796184ms","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-20T05:33:02.183935Z","caller":"traceutil/trace.go:171","msg":"trace[540551116] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"735.832516ms","start":"2026-03-20T05:33:01.448072Z","end":"2026-03-20T05:33:02.183905Z","steps":["trace[540551116] 'process raft request'  (duration: 449.753741ms)","trace[540551116] 'compare'  (duration: 285.348602ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:02.184116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:01.448055Z","time spent":"735.971178ms","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:923 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487165268839053123 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >"}
{"level":"info","ts":"2026-03-20T05:33:02.591972Z","caller":"traceutil/trace.go:171","msg":"trace[71182710] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"209.867622ms","start":"2026-03-20T05:33:02.382077Z","end":"2026-03-20T05:33:02.591945Z","steps":["trace[71182710] 'process raft request'  (duration: 209.672628ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:03.947432Z","caller":"traceutil/trace.go:171","msg":"trace[315048300] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"251.779659ms","start":"2026-03-20T05:33:03.695615Z","end":"2026-03-20T05:33:03.947394Z","steps":["trace[315048300] 'process raft request'  (duration: 251.594776ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:04.459279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537305693828952,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-20T05:33:04.883068Z","caller":"traceutil/trace.go:171","msg":"trace[491391414] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.18630109s","start":"2026-03-20T05:33:03.696742Z","end":"2026-03-20T05:33:04.883043Z","steps":["trace[491391414] 'process raft request'  (duration: 1.186226239s)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:04.88309Z","caller":"traceutil/trace.go:171","msg":"trace[882350542] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.187419471s","start":"2026-03-20T05:33:03.695645Z","end":"2026-03-20T05:33:04.883065Z","steps":["trace[882350542] 'process raft request'  (duration: 1.187084555s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:04.883236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:03.696731Z","time spent":"1.186410762s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:967 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:04.883255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:03.695627Z","time spent":"1.187539063s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:970 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-20T05:33:04.883275Z","caller":"traceutil/trace.go:171","msg":"trace[630571669] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.18677988s","start":"2026-03-20T05:33:03.696473Z","end":"2026-03-20T05:33:04.883252Z","steps":["trace[630571669] 'process raft request'  (duration: 1.186446884s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:04.883379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:03.696459Z","time spent":"1.186858681s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:969 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:33:05.64843Z","caller":"traceutil/trace.go:171","msg":"trace[1714983405] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1017; }","duration":"1.689606474s","start":"2026-03-20T05:33:03.958797Z","end":"2026-03-20T05:33:05.648403Z","steps":["trace[1714983405] 'read index received'  (duration: 923.929234ms)","trace[1714983405] 'applied index is now lower than readState.Index'  (duration: 765.67612ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:05.648635Z","caller":"traceutil/trace.go:171","msg":"trace[1959704290] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.389350757s","start":"2026-03-20T05:33:04.259257Z","end":"2026-03-20T05:33:05.648608Z","steps":["trace[1959704290] 'process raft request'  (duration: 1.388883898s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.648846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:04.259231Z","time spent":"1.38950136s","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5735,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:957 > success:<request_put:<key:\"/registry/minions/instance\" value_size:5701 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-03-20T05:33:05.648861Z","caller":"traceutil/trace.go:171","msg":"trace[1447897704] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"777.365516ms","start":"2026-03-20T05:33:04.871441Z","end":"2026-03-20T05:33:05.648806Z","steps":["trace[1447897704] 'process raft request'  (duration: 776.857377ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.648942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.417035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:33:05.649017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:04.871418Z","time spent":"777.513319ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:935 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"info","ts":"2026-03-20T05:33:05.649143Z","caller":"traceutil/trace.go:171","msg":"trace[891940594] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:979; }","duration":"380.585798ms","start":"2026-03-20T05:33:05.268427Z","end":"2026-03-20T05:33:05.649013Z","steps":["trace[891940594] 'agreement among raft nodes before linearized reading'  (duration: 380.368614ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.649101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.038563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-03-20T05:33:05.649245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:05.268394Z","time spent":"380.823133ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "}
{"level":"warn","ts":"2026-03-20T05:33:05.649271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152789849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-20T05:33:05.649277Z","caller":"traceutil/trace.go:171","msg":"trace[1723962643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"858.273777ms","start":"2026-03-20T05:33:04.790985Z","end":"2026-03-20T05:33:05.649259Z","steps":["trace[1723962643] 'agreement among raft nodes before linearized reading'  (duration: 857.832929ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.649348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:04.790962Z","time spent":"858.367249ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:33:05.649343Z","caller":"traceutil/trace.go:171","msg":"trace[2022666918] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:979; }","duration":"1.152929481s","start":"2026-03-20T05:33:04.496389Z","end":"2026-03-20T05:33:05.649319Z","steps":["trace[2022666918] 'agreement among raft nodes before linearized reading'  (duration: 1.152366411s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.649413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:04.496365Z","time spent":"1.153027423s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "}
{"level":"warn","ts":"2026-03-20T05:33:05.650796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692011799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:05.650853Z","caller":"traceutil/trace.go:171","msg":"trace[1770910367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"1.692117861s","start":"2026-03-20T05:33:03.95872Z","end":"2026-03-20T05:33:05.650838Z","steps":["trace[1770910367] 'agreement among raft nodes before linearized reading'  (duration: 1.68989987s)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:05.650892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:03.958701Z","time spent":"1.692183502s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:33:06.562571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.551795ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828965 > lease_revoke:<id:3e459d09b99f36de>","response":"size:29"}
{"level":"info","ts":"2026-03-20T05:33:06.562788Z","caller":"traceutil/trace.go:171","msg":"trace[290372536] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"610.379489ms","start":"2026-03-20T05:33:05.952356Z","end":"2026-03-20T05:33:06.562735Z","steps":["trace[290372536] 'read index received'  (duration: 173.518558ms)","trace[290372536] 'applied index is now lower than readState.Index'  (duration: 436.859441ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:06.562952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.592613ms","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-03-20T05:33:06.562985Z","caller":"traceutil/trace.go:171","msg":"trace[1335338730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"610.635193ms","start":"2026-03-20T05:33:05.952341Z","end":"2026-03-20T05:33:06.562976Z","steps":["trace[1335338730] 'agreement among raft nodes before linearized reading'  (duration: 610.483151ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:06.563024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:05.95228Z","time spent":"610.734525ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-20T05:33:07.175711Z","caller":"traceutil/trace.go:171","msg":"trace[530578494] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"287.177736ms","start":"2026-03-20T05:33:06.88851Z","end":"2026-03-20T05:33:07.175688Z","steps":["trace[530578494] 'read index received'  (duration: 286.929351ms)","trace[530578494] 'applied index is now lower than readState.Index'  (duration: 247.345µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:07.175863Z","caller":"traceutil/trace.go:171","msg":"trace[1127491565] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"608.866362ms","start":"2026-03-20T05:33:06.566963Z","end":"2026-03-20T05:33:07.17583Z","steps":["trace[1127491565] 'process raft request'  (duration: 608.574366ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.176077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.300819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-03-20T05:33:07.176099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.527033ms","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-03-20T05:33:07.176126Z","caller":"traceutil/trace.go:171","msg":"trace[597179254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"287.35273ms","start":"2026-03-20T05:33:06.888753Z","end":"2026-03-20T05:33:07.176106Z","steps":["trace[597179254] 'agreement among raft nodes before linearized reading'  (duration: 287.266809ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:07.176152Z","caller":"traceutil/trace.go:171","msg":"trace[384114040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"287.637155ms","start":"2026-03-20T05:33:06.888502Z","end":"2026-03-20T05:33:07.176139Z","steps":["trace[384114040] 'agreement among raft nodes before linearized reading'  (duration: 287.426161ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.17598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.35535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:07.176308Z","caller":"traceutil/trace.go:171","msg":"trace[1937470214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"218.713865ms","start":"2026-03-20T05:33:06.957568Z","end":"2026-03-20T05:33:07.176282Z","steps":["trace[1937470214] 'agreement among raft nodes before linearized reading'  (duration: 218.366509ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.176466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.958991ms","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-03-20T05:33:07.176547Z","caller":"traceutil/trace.go:171","msg":"trace[994394435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"288.078633ms","start":"2026-03-20T05:33:06.888459Z","end":"2026-03-20T05:33:07.176538Z","steps":["trace[994394435] 'agreement among raft nodes before linearized reading'  (duration: 287.35939ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.176534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:06.566942Z","time spent":"609.122797ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:974 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:07.584214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.404054ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693828971 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:977 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:33:07.584459Z","caller":"traceutil/trace.go:171","msg":"trace[1287455773] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"402.31326ms","start":"2026-03-20T05:33:07.182122Z","end":"2026-03-20T05:33:07.584435Z","steps":["trace[1287455773] 'process raft request'  (duration: 124.58307ms)","trace[1287455773] 'compare'  (duration: 277.253662ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:07.584509Z","caller":"traceutil/trace.go:171","msg":"trace[1857954647] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"402.077976ms","start":"2026-03-20T05:33:07.182414Z","end":"2026-03-20T05:33:07.584492Z","steps":["trace[1857954647] 'process raft request'  (duration: 402.021105ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.584546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:07.182105Z","time spent":"402.399942ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:977 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-20T05:33:07.584585Z","caller":"traceutil/trace.go:171","msg":"trace[1694348792] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"402.208098ms","start":"2026-03-20T05:33:07.18234Z","end":"2026-03-20T05:33:07.584548Z","steps":["trace[1694348792] 'process raft request'  (duration: 402.015085ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:07.584607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:07.182393Z","time spent":"402.164627ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:976 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:07.584757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:07.182316Z","time spent":"402.349632ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:975 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-03-20T05:33:07.834514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.790784ms","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-03-20T05:33:07.834601Z","caller":"traceutil/trace.go:171","msg":"trace[77654479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"145.925676ms","start":"2026-03-20T05:33:07.688656Z","end":"2026-03-20T05:33:07.834582Z","steps":["trace[77654479] 'range keys from in-memory index tree'  (duration: 145.589ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:08.20303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.898423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:08.203089Z","caller":"traceutil/trace.go:171","msg":"trace[875122699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"244.002215ms","start":"2026-03-20T05:33:07.959074Z","end":"2026-03-20T05:33:08.203076Z","steps":["trace[875122699] 'range keys from in-memory index tree'  (duration: 243.778901ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:08.203211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.79581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:08.203311Z","caller":"traceutil/trace.go:171","msg":"trace[1748119745] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:987; }","duration":"251.934262ms","start":"2026-03-20T05:33:07.951354Z","end":"2026-03-20T05:33:08.203289Z","steps":["trace[1748119745] 'range keys from in-memory index tree'  (duration: 251.725489ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:09.701454Z","caller":"traceutil/trace.go:171","msg":"trace[436785019] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"108.753656ms","start":"2026-03-20T05:33:09.592671Z","end":"2026-03-20T05:33:09.701425Z","steps":["trace[436785019] 'process raft request'  (duration: 108.569413ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:09.702454Z","caller":"traceutil/trace.go:171","msg":"trace[1646242495] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"108.336149ms","start":"2026-03-20T05:33:09.594093Z","end":"2026-03-20T05:33:09.702429Z","steps":["trace[1646242495] 'process raft request'  (duration: 108.292178ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:09.70275Z","caller":"traceutil/trace.go:171","msg":"trace[1658010658] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"109.721724ms","start":"2026-03-20T05:33:09.593016Z","end":"2026-03-20T05:33:09.702738Z","steps":["trace[1658010658] 'process raft request'  (duration: 109.266196ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:10.714466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.204048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:33:10.714586Z","caller":"traceutil/trace.go:171","msg":"trace[1902535659] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:996; }","duration":"224.350441ms","start":"2026-03-20T05:33:10.490194Z","end":"2026-03-20T05:33:10.714544Z","steps":["trace[1902535659] 'range keys from in-memory index tree'  (duration: 224.025755ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:10.714783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.758957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:10.714865Z","caller":"traceutil/trace.go:171","msg":"trace[2053503583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"754.868019ms","start":"2026-03-20T05:33:09.959975Z","end":"2026-03-20T05:33:10.714843Z","steps":["trace[2053503583] 'range keys from in-memory index tree'  (duration: 754.687066ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:10.714931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:09.959955Z","time spent":"754.96454ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-20T05:33:10.714487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.188438ms","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-03-20T05:33:10.715133Z","caller":"traceutil/trace.go:171","msg":"trace[1874255505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"863.875291ms","start":"2026-03-20T05:33:09.851235Z","end":"2026-03-20T05:33:10.715111Z","steps":["trace[1874255505] 'range keys from in-memory index tree'  (duration: 863.022605ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:10.71518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:09.851213Z","time spent":"863.951892ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-20T05:33:10.97653Z","caller":"traceutil/trace.go:171","msg":"trace[372305386] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"256.500937ms","start":"2026-03-20T05:33:10.719973Z","end":"2026-03-20T05:33:10.976474Z","steps":["trace[372305386] 'process raft request'  (duration: 256.331884ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:11.855935Z","caller":"traceutil/trace.go:171","msg":"trace[1753749975] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"146.618129ms","start":"2026-03-20T05:33:11.709284Z","end":"2026-03-20T05:33:11.855902Z","steps":["trace[1753749975] 'process raft request'  (duration: 146.460106ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:11.857156Z","caller":"traceutil/trace.go:171","msg":"trace[1780957335] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"146.413975ms","start":"2026-03-20T05:33:11.710727Z","end":"2026-03-20T05:33:11.857141Z","steps":["trace[1780957335] 'process raft request'  (duration: 146.021958ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:11.857479Z","caller":"traceutil/trace.go:171","msg":"trace[215827105] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"144.768755ms","start":"2026-03-20T05:33:11.71233Z","end":"2026-03-20T05:33:11.857098Z","steps":["trace[215827105] 'process raft request'  (duration: 144.657683ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:14.101953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.607714ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693829043 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" mod_revision:992 > success:<request_put:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" value_size:404 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:33:14.102174Z","caller":"traceutil/trace.go:171","msg":"trace[299298876] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"241.809364ms","start":"2026-03-20T05:33:13.860348Z","end":"2026-03-20T05:33:14.102157Z","steps":["trace[299298876] 'read index received'  (duration: 53.371µs)","trace[299298876] 'applied index is now lower than readState.Index'  (duration: 241.754163ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:14.102326Z","caller":"traceutil/trace.go:171","msg":"trace[1384301154] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"866.362137ms","start":"2026-03-20T05:33:13.235912Z","end":"2026-03-20T05:33:14.102274Z","steps":["trace[1384301154] 'process raft request'  (duration: 242.351604ms)","trace[1384301154] 'compare'  (duration: 623.496463ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:33:14.102449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.1042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-03-20T05:33:14.102517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.492799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"warn","ts":"2026-03-20T05:33:14.102506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.508499ms","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-03-20T05:33:14.102589Z","caller":"traceutil/trace.go:171","msg":"trace[1594491546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"241.600841ms","start":"2026-03-20T05:33:13.86097Z","end":"2026-03-20T05:33:14.10257Z","steps":["trace[1594491546] 'agreement among raft nodes before linearized reading'  (duration: 241.422048ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:14.102553Z","caller":"traceutil/trace.go:171","msg":"trace[2092075432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"242.207351ms","start":"2026-03-20T05:33:13.860332Z","end":"2026-03-20T05:33:14.102539Z","steps":["trace[2092075432] 'agreement among raft nodes before linearized reading'  (duration: 241.954347ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:14.102453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.148397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-20T05:33:14.102702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:13.235892Z","time spent":"866.483029ms","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" mod_revision:992 > success:<request_put:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" value_size:404 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-03-20T05:33:14.102768Z","caller":"traceutil/trace.go:171","msg":"trace[419490422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"141.474884ms","start":"2026-03-20T05:33:13.961249Z","end":"2026-03-20T05:33:14.102724Z","steps":["trace[419490422] 'agreement among raft nodes before linearized reading'  (duration: 141.129358ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:14.102863Z","caller":"traceutil/trace.go:171","msg":"trace[1055889417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"241.650022ms","start":"2026-03-20T05:33:13.860926Z","end":"2026-03-20T05:33:14.102576Z","steps":["trace[1055889417] 'agreement among raft nodes before linearized reading'  (duration: 241.449308ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:16.011438Z","caller":"traceutil/trace.go:171","msg":"trace[141769183] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"110.337846ms","start":"2026-03-20T05:33:15.901078Z","end":"2026-03-20T05:33:16.011416Z","steps":["trace[141769183] 'process raft request'  (duration: 110.112602ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:16.011499Z","caller":"traceutil/trace.go:171","msg":"trace[591591578] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"111.171271ms","start":"2026-03-20T05:33:15.900306Z","end":"2026-03-20T05:33:16.011478Z","steps":["trace[591591578] 'process raft request'  (duration: 102.917878ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:16.589485Z","caller":"traceutil/trace.go:171","msg":"trace[164453244] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"251.700748ms","start":"2026-03-20T05:33:16.337759Z","end":"2026-03-20T05:33:16.589459Z","steps":["trace[164453244] 'process raft request'  (duration: 250.892563ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:16.589655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.939761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-20T05:33:16.589711Z","caller":"traceutil/trace.go:171","msg":"trace[2100661943] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1041; }","duration":"232.043144ms","start":"2026-03-20T05:33:16.357658Z","end":"2026-03-20T05:33:16.589701Z","steps":["trace[2100661943] 'agreement among raft nodes before linearized reading'  (duration: 231.882641ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:16.589475Z","caller":"traceutil/trace.go:171","msg":"trace[1866865211] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"231.749878ms","start":"2026-03-20T05:33:16.357699Z","end":"2026-03-20T05:33:16.589449Z","steps":["trace[1866865211] 'read index received'  (duration: 230.965673ms)","trace[1866865211] 'applied index is now lower than readState.Index'  (duration: 782.435µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:21.12652Z","caller":"traceutil/trace.go:171","msg":"trace[578767622] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"106.385373ms","start":"2026-03-20T05:33:21.020107Z","end":"2026-03-20T05:33:21.126492Z","steps":["trace[578767622] 'process raft request'  (duration: 106.22284ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:21.418696Z","caller":"traceutil/trace.go:171","msg":"trace[1696146549] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"144.215834ms","start":"2026-03-20T05:33:21.274435Z","end":"2026-03-20T05:33:21.418651Z","steps":["trace[1696146549] 'process raft request'  (duration: 49.322244ms)","trace[1696146549] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 94.656776ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:22.422813Z","caller":"traceutil/trace.go:171","msg":"trace[1880918437] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"108.43202ms","start":"2026-03-20T05:33:22.314358Z","end":"2026-03-20T05:33:22.42279Z","steps":["trace[1880918437] 'process raft request'  (duration: 108.072614ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:22.426879Z","caller":"traceutil/trace.go:171","msg":"trace[66333011] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"112.260122ms","start":"2026-03-20T05:33:22.314593Z","end":"2026-03-20T05:33:22.426853Z","steps":["trace[66333011] 'process raft request'  (duration: 112.114299ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:33:47.821005Z","caller":"traceutil/trace.go:171","msg":"trace[261607712] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"189.630026ms","start":"2026-03-20T05:33:47.631348Z","end":"2026-03-20T05:33:47.820978Z","steps":["trace[261607712] 'read index received'  (duration: 189.497654ms)","trace[261607712] 'applied index is now lower than readState.Index'  (duration: 131.172µs)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:33:47.821159Z","caller":"traceutil/trace.go:171","msg":"trace[1947552750] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"206.56439ms","start":"2026-03-20T05:33:47.614563Z","end":"2026-03-20T05:33:47.821127Z","steps":["trace[1947552750] 'process raft request'  (duration: 206.278175ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:33:47.821305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.943002ms","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-03-20T05:33:47.821376Z","caller":"traceutil/trace.go:171","msg":"trace[1973938957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"190.060814ms","start":"2026-03-20T05:33:47.631292Z","end":"2026-03-20T05:33:47.821352Z","steps":["trace[1973938957] 'agreement among raft nodes before linearized reading'  (duration: 189.82334ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:34:07.691051Z","caller":"traceutil/trace.go:171","msg":"trace[1355242634] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"172.748911ms","start":"2026-03-20T05:34:07.518271Z","end":"2026-03-20T05:34:07.69102Z","steps":["trace[1355242634] 'process raft request'  (duration: 172.529416ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:34:44.422473Z","caller":"traceutil/trace.go:171","msg":"trace[1591156159] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"110.316704ms","start":"2026-03-20T05:34:44.312129Z","end":"2026-03-20T05:34:44.422446Z","steps":["trace[1591156159] 'process raft request'  (duration: 110.175192ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:35:20.41002Z","caller":"traceutil/trace.go:171","msg":"trace[1569103426] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"161.714787ms","start":"2026-03-20T05:35:20.248189Z","end":"2026-03-20T05:35:20.409904Z","steps":["trace[1569103426] 'process raft request'  (duration: 161.100405ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:35:20.668706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.632657ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710537305693830384 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1544 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-20T05:35:20.668823Z","caller":"traceutil/trace.go:171","msg":"trace[1391609240] linearizableReadLoop","detail":"{readStateIndex:1622; appliedIndex:1621; }","duration":"170.681273ms","start":"2026-03-20T05:35:20.498123Z","end":"2026-03-20T05:35:20.668805Z","steps":["trace[1391609240] 'read index received'  (duration: 52.261µs)","trace[1391609240] 'applied index is now lower than readState.Index'  (duration: 170.627532ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:35:20.66897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.852995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-20T05:35:20.669004Z","caller":"traceutil/trace.go:171","msg":"trace[1750261491] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:1552; }","duration":"170.927567ms","start":"2026-03-20T05:35:20.498065Z","end":"2026-03-20T05:35:20.668992Z","steps":["trace[1750261491] 'agreement among raft nodes before linearized reading'  (duration: 170.792884ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:35:20.668981Z","caller":"traceutil/trace.go:171","msg":"trace[2038125086] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"254.617298ms","start":"2026-03-20T05:35:20.414332Z","end":"2026-03-20T05:35:20.668949Z","steps":["trace[2038125086] 'process raft request'  (duration: 80.637774ms)","trace[2038125086] 'compare'  (duration: 173.504085ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:35:20.796315Z","caller":"traceutil/trace.go:171","msg":"trace[16258341] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"111.1323ms","start":"2026-03-20T05:35:20.685165Z","end":"2026-03-20T05:35:20.796297Z","steps":["trace[16258341] 'process raft request'  (duration: 107.91376ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:35:39.301109Z","caller":"traceutil/trace.go:171","msg":"trace[381443913] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"125.113908ms","start":"2026-03-20T05:35:39.175958Z","end":"2026-03-20T05:35:39.301072Z","steps":["trace[381443913] 'process raft request'  (duration: 124.802202ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:35:54.775106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.815467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"}
{"level":"info","ts":"2026-03-20T05:35:54.775231Z","caller":"traceutil/trace.go:171","msg":"trace[1980252968] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1691; }","duration":"141.998841ms","start":"2026-03-20T05:35:54.633206Z","end":"2026-03-20T05:35:54.775205Z","steps":["trace[1980252968] 'range keys from in-memory index tree'  (duration: 141.581463ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:35:56.153109Z","caller":"traceutil/trace.go:171","msg":"trace[1088653195] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"131.500377ms","start":"2026-03-20T05:35:56.02158Z","end":"2026-03-20T05:35:56.15308Z","steps":["trace[1088653195] 'process raft request'  (duration: 87.025323ms)","trace[1088653195] 'compare'  (duration: 44.319971ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:35:56.801542Z","caller":"traceutil/trace.go:171","msg":"trace[561979231] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"146.977843ms","start":"2026-03-20T05:35:56.65452Z","end":"2026-03-20T05:35:56.801498Z","steps":["trace[561979231] 'process raft request'  (duration: 123.388396ms)","trace[561979231] 'compare'  (duration: 23.462515ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:36:18.52371Z","caller":"traceutil/trace.go:171","msg":"trace[614909506] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"136.374784ms","start":"2026-03-20T05:36:18.387305Z","end":"2026-03-20T05:36:18.52368Z","steps":["trace[614909506] 'read index received'  (duration: 136.21703ms)","trace[614909506] 'applied index is now lower than readState.Index'  (duration: 156.334µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:36:18.523926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.585298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-20T05:36:18.523968Z","caller":"traceutil/trace.go:171","msg":"trace[1130497242] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1818; }","duration":"136.7162ms","start":"2026-03-20T05:36:18.387242Z","end":"2026-03-20T05:36:18.523959Z","steps":["trace[1130497242] 'agreement among raft nodes before linearized reading'  (duration: 136.562727ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:36:18.524016Z","caller":"traceutil/trace.go:171","msg":"trace[1523605738] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"269.957645ms","start":"2026-03-20T05:36:18.25402Z","end":"2026-03-20T05:36:18.523978Z","steps":["trace[1523605738] 'process raft request'  (duration: 269.478656ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:36:19.249721Z","caller":"traceutil/trace.go:171","msg":"trace[1935117215] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"228.368565ms","start":"2026-03-20T05:36:19.02132Z","end":"2026-03-20T05:36:19.249688Z","steps":["trace[1935117215] 'process raft request'  (duration: 197.916741ms)","trace[1935117215] 'compare'  (duration: 29.63834ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:36:20.055888Z","caller":"traceutil/trace.go:171","msg":"trace[86604992] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"231.550584ms","start":"2026-03-20T05:36:19.824292Z","end":"2026-03-20T05:36:20.055842Z","steps":["trace[86604992] 'process raft request'  (duration: 133.746774ms)","trace[86604992] 'compare'  (duration: 97.514945ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:36:20.092681Z","caller":"traceutil/trace.go:171","msg":"trace[1600441003] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"265.141625ms","start":"2026-03-20T05:36:19.827497Z","end":"2026-03-20T05:36:20.092639Z","steps":["trace[1600441003] 'process raft request'  (duration: 264.947482ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:36:51.832553Z","caller":"traceutil/trace.go:171","msg":"trace[1479671528] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2052; }","duration":"117.83083ms","start":"2026-03-20T05:36:51.714693Z","end":"2026-03-20T05:36:51.832524Z","steps":["trace[1479671528] 'read index received'  (duration: 56.23627ms)","trace[1479671528] 'applied index is now lower than readState.Index'  (duration: 61.59318ms)"],"step_count":2}
{"level":"info","ts":"2026-03-20T05:36:51.832758Z","caller":"traceutil/trace.go:171","msg":"trace[663274312] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"122.274433ms","start":"2026-03-20T05:36:51.710449Z","end":"2026-03-20T05:36:51.832723Z","steps":["trace[663274312] 'process raft request'  (duration: 122.029417ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:36:51.832788Z","caller":"traceutil/trace.go:171","msg":"trace[1106750535] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"122.512816ms","start":"2026-03-20T05:36:51.710182Z","end":"2026-03-20T05:36:51.832695Z","steps":["trace[1106750535] 'process raft request'  (duration: 60.754423ms)","trace[1106750535] 'compare'  (duration: 61.413707ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-20T05:36:51.833071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.177277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hpx29\" ","response":"range_response_count:1 size:13720"}
{"level":"info","ts":"2026-03-20T05:36:51.833128Z","caller":"traceutil/trace.go:171","msg":"trace[764753406] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hpx29; range_end:; response_count:1; response_revision:1963; }","duration":"118.490142ms","start":"2026-03-20T05:36:51.714618Z","end":"2026-03-20T05:36:51.833108Z","steps":["trace[764753406] 'agreement among raft nodes before linearized reading'  (duration: 118.108766ms)"],"step_count":1}
{"level":"info","ts":"2026-03-20T05:36:51.943008Z","caller":"traceutil/trace.go:171","msg":"trace[1650161061] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"104.886051ms","start":"2026-03-20T05:36:51.83809Z","end":"2026-03-20T05:36:51.942976Z","steps":["trace[1650161061] 'process raft request'  (duration: 96.840862ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-20T05:36:52.309286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.56832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hpx29\" ","response":"range_response_count:1 size:13720"}
{"level":"info","ts":"2026-03-20T05:36:52.309418Z","caller":"traceutil/trace.go:171","msg":"trace[476432547] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hpx29; range_end:; response_count:1; response_revision:1965; }","duration":"200.742263ms","start":"2026-03-20T05:36:52.108639Z","end":"2026-03-20T05:36:52.309381Z","steps":["trace[476432547] 'range keys from in-memory index tree'  (duration: 200.326516ms)"],"step_count":1}