{"level":"warn","ts":"2026-02-28T01:30:24.914343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T01:30:24.914437Z","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-02-28T01:30:24.914506Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T01:30:24.914519Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-02-28T01:30:24.914539Z","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-02-28T01:30:24.915192Z","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-02-28T01:30:24.915264Z","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-02-28T01:30:24.921539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.084632ms"} {"level":"info","ts":"2026-02-28T01:30:24.929352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-02-28T01:30:24.929573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T01:30:24.929663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-02-28T01:30:24.929682Z","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-02-28T01:30:24.929755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-02-28T01:30:24.929817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-02-28T01:30:24.939813Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T01:30:24.943313Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T01:30:24.944149Z","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-02-28T01:30:24.946748Z","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-02-28T01:30:24.948554Z","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-02-28T01:30:24.948711Z","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-02-28T01:30:24.947957Z","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-02-28T01:30:24.948761Z","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-02-28T01:30:24.949102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-02-28T01:30:24.949777Z","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-02-28T01:30:24.952271Z","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-02-28T01:30:24.95237Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-28T01:30:24.952438Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-28T01:30:24.952599Z","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-02-28T01:30:24.952696Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T01:30:25.730873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T01:30:25.730931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T01:30:25.73097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-02-28T01:30:25.730987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-02-28T01:30:25.730996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-28T01:30:25.731007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-02-28T01:30:25.731017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-28T01:30:26.029994Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:30:26.70058Z","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-02-28T01:30:26.700791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:30:26.70108Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:30:26.701134Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T01:30:26.701261Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T01:30:26.704268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-02-28T01:30:26.704687Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-28T01:30:26.987735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.981153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"context canceled"} {"level":"warn","ts":"2026-02-28T01:30:26.98784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.351552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-28T01:30:26.987948Z","caller":"traceutil/trace.go:171","msg":"trace[1046471767] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"128.501115ms","start":"2026-02-28T01:30:26.859429Z","end":"2026-02-28T01:30:26.98793Z","steps":["trace[1046471767] 'agreement among raft nodes before linearized reading' (duration: 128.367642ms)"],"step_count":1} 2026/02/28 01:30:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-28T01:30:26.987893Z","caller":"traceutil/trace.go:171","msg":"trace[1856220048] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"128.207818ms","start":"2026-02-28T01:30:26.859658Z","end":"2026-02-28T01:30:26.987866Z","steps":["trace[1856220048] 'agreement among raft nodes before linearized reading' (duration: 127.990003ms)"],"step_count":1} 2026/02/28 01:30:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-28T01:30:27.250654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536859632214275,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T01:30:27.270047Z","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-02-28T01:30:27.270235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:30:27.270326Z","caller":"traceutil/trace.go:171","msg":"trace[2110815530] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"521.525376ms","start":"2026-02-28T01:30:26.74876Z","end":"2026-02-28T01:30:27.270286Z","steps":["trace[2110815530] 'read index received' (duration: 521.201658ms)","trace[2110815530] 'applied index is now lower than readState.Index' (duration: 321.848µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:30:27.270315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-28T01:30:27.270621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.666849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.270662Z","caller":"traceutil/trace.go:171","msg":"trace[157933368] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"521.761191ms","start":"2026-02-28T01:30:26.748891Z","end":"2026-02-28T01:30:27.270652Z","steps":["trace[157933368] 'agreement among raft nodes before linearized reading' (duration: 521.591557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.270667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.912166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.270639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.838483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.270684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.425776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.270692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.748851Z","time spent":"521.832023ms","remote":"127.0.0.1:54862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:30:27.27077Z","caller":"traceutil/trace.go:171","msg":"trace[142953447] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"217.037999ms","start":"2026-02-28T01:30:27.05369Z","end":"2026-02-28T01:30:27.270728Z","steps":["trace[142953447] 'agreement among raft nodes before linearized reading' (duration: 216.821874ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.270792Z","caller":"traceutil/trace.go:171","msg":"trace[1261574792] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"422.555789ms","start":"2026-02-28T01:30:26.848211Z","end":"2026-02-28T01:30:27.270767Z","steps":["trace[1261574792] 'agreement among raft nodes before linearized reading' (duration: 422.377355ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.270807Z","caller":"traceutil/trace.go:171","msg":"trace[1607704420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"521.988776ms","start":"2026-02-28T01:30:26.748752Z","end":"2026-02-28T01:30:27.27074Z","steps":["trace[1607704420] 'agreement among raft nodes before linearized reading' (duration: 521.669229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.270847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.848206Z","time spent":"422.629111ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.270943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.368986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.270983Z","caller":"traceutil/trace.go:171","msg":"trace[1847620972] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"362.409207ms","start":"2026-02-28T01:30:26.908557Z","end":"2026-02-28T01:30:27.270966Z","steps":["trace[1847620972] 'agreement among raft nodes before linearized reading' (duration: 362.330645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.27102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.908552Z","time spent":"362.459539ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.270981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.748705Z","time spent":"522.15504ms","remote":"127.0.0.1:54862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":121,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.271179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.848954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271245Z","caller":"traceutil/trace.go:171","msg":"trace[1272271550] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1; }","duration":"113.944456ms","start":"2026-02-28T01:30:27.157285Z","end":"2026-02-28T01:30:27.271229Z","steps":["trace[1272271550] 'agreement among raft nodes before linearized reading' (duration: 113.831653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.877178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.27133Z","caller":"traceutil/trace.go:171","msg":"trace[988249447] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"362.929409ms","start":"2026-02-28T01:30:26.90839Z","end":"2026-02-28T01:30:27.271319Z","steps":["trace[988249447] 'agreement among raft nodes before linearized reading' (duration: 362.840487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.908382Z","time spent":"362.979571ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.271391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.358698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271436Z","caller":"traceutil/trace.go:171","msg":"trace[438208180] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1; }","duration":"127.44362ms","start":"2026-02-28T01:30:27.143978Z","end":"2026-02-28T01:30:27.271422Z","steps":["trace[438208180] 'agreement among raft nodes before linearized reading' (duration: 127.372519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.381309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271547Z","caller":"traceutil/trace.go:171","msg":"trace[1974062217] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"375.42981ms","start":"2026-02-28T01:30:26.896108Z","end":"2026-02-28T01:30:27.271538Z","steps":["trace[1974062217] 'agreement among raft nodes before linearized reading' (duration: 375.376829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.896095Z","time spent":"375.483692ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.271634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.848413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271679Z","caller":"traceutil/trace.go:171","msg":"trace[651424350] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1; }","duration":"131.896184ms","start":"2026-02-28T01:30:27.139773Z","end":"2026-02-28T01:30:27.271669Z","steps":["trace[651424350] 'agreement among raft nodes before linearized reading' (duration: 131.835173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.643206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.2718Z","caller":"traceutil/trace.go:171","msg":"trace[1723098893] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"375.710267ms","start":"2026-02-28T01:30:26.896081Z","end":"2026-02-28T01:30:27.271791Z","steps":["trace[1723098893] 'agreement among raft nodes before linearized reading' (duration: 375.641335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.27182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.061614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271862Z","caller":"traceutil/trace.go:171","msg":"trace[1735076368] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"146.156296ms","start":"2026-02-28T01:30:27.125697Z","end":"2026-02-28T01:30:27.271853Z","steps":["trace[1735076368] 'agreement among raft nodes before linearized reading' (duration: 146.097285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.271904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.464007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.271934Z","caller":"traceutil/trace.go:171","msg":"trace[1714792406] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"389.508739ms","start":"2026-02-28T01:30:26.882417Z","end":"2026-02-28T01:30:27.271926Z","steps":["trace[1714792406] 'agreement among raft nodes before linearized reading' (duration: 389.468128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.27196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.882407Z","time spent":"389.54559ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.272029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.287909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272066Z","caller":"traceutil/trace.go:171","msg":"trace[895476374] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"146.337451ms","start":"2026-02-28T01:30:27.125719Z","end":"2026-02-28T01:30:27.272057Z","steps":["trace[895476374] 'agreement among raft nodes before linearized reading' (duration: 146.28666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.254095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272244Z","caller":"traceutil/trace.go:171","msg":"trace[1132368602] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"160.299396ms","start":"2026-02-28T01:30:27.111933Z","end":"2026-02-28T01:30:27.272233Z","steps":["trace[1132368602] 'agreement among raft nodes before linearized reading' (duration: 160.242965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.699496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272439Z","caller":"traceutil/trace.go:171","msg":"trace[399737073] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"160.759697ms","start":"2026-02-28T01:30:27.111672Z","end":"2026-02-28T01:30:27.272431Z","steps":["trace[399737073] 'agreement among raft nodes before linearized reading' (duration: 160.705406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.00297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272499Z","caller":"traceutil/trace.go:171","msg":"trace[580072036] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"402.051341ms","start":"2026-02-28T01:30:26.870428Z","end":"2026-02-28T01:30:27.272479Z","steps":["trace[580072036] 'agreement among raft nodes before linearized reading' (duration: 401.982569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.870411Z","time spent":"402.117482ms","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.272602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.532935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.272634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.385847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272675Z","caller":"traceutil/trace.go:171","msg":"trace[1802214768] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"178.445889ms","start":"2026-02-28T01:30:27.094217Z","end":"2026-02-28T01:30:27.272663Z","steps":["trace[1802214768] 'agreement among raft nodes before linearized reading' (duration: 178.390958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.401532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.272763Z","caller":"traceutil/trace.go:171","msg":"trace[1065745167] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"436.466483ms","start":"2026-02-28T01:30:26.836288Z","end":"2026-02-28T01:30:27.272755Z","steps":["trace[1065745167] 'agreement among raft nodes before linearized reading' (duration: 436.369831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.836284Z","time spent":"436.500374ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.273064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.185212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.273107Z","caller":"traceutil/trace.go:171","msg":"trace[489873503] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"193.245354ms","start":"2026-02-28T01:30:27.079853Z","end":"2026-02-28T01:30:27.273098Z","steps":["trace[489873503] 'agreement among raft nodes before linearized reading' (duration: 193.004829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.273265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.641544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.273275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.909641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.273298Z","caller":"traceutil/trace.go:171","msg":"trace[1803681459] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"193.698075ms","start":"2026-02-28T01:30:27.079592Z","end":"2026-02-28T01:30:27.27329Z","steps":["trace[1803681459] 'agreement among raft nodes before linearized reading' (duration: 193.642654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.273318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.039726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.273367Z","caller":"traceutil/trace.go:171","msg":"trace[566979450] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"437.122608ms","start":"2026-02-28T01:30:26.836236Z","end":"2026-02-28T01:30:27.273359Z","steps":["trace[566979450] 'agreement among raft nodes before linearized reading' (duration: 437.054297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.273396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.836219Z","time spent":"437.169019ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.273419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.837625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.273467Z","caller":"traceutil/trace.go:171","msg":"trace[1078492946] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"207.896635ms","start":"2026-02-28T01:30:27.065551Z","end":"2026-02-28T01:30:27.273448Z","steps":["trace[1078492946] 'agreement among raft nodes before linearized reading' (duration: 207.846464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.273636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.102791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.273675Z","caller":"traceutil/trace.go:171","msg":"trace[6242225] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"208.156231ms","start":"2026-02-28T01:30:27.065505Z","end":"2026-02-28T01:30:27.273662Z","steps":["trace[6242225] 'agreement among raft nodes before linearized reading' (duration: 208.108ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.272638Z","caller":"traceutil/trace.go:171","msg":"trace[2072762071] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"424.618407ms","start":"2026-02-28T01:30:26.848009Z","end":"2026-02-28T01:30:27.272628Z","steps":["trace[2072762071] 'agreement among raft nodes before linearized reading' (duration: 424.536965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.273812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.889705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.273846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.848002Z","time spent":"425.831435ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 "} {"level":"info","ts":"2026-02-28T01:30:27.273864Z","caller":"traceutil/trace.go:171","msg":"trace[2114127817] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"219.983518ms","start":"2026-02-28T01:30:27.053867Z","end":"2026-02-28T01:30:27.27385Z","steps":["trace[2114127817] 'agreement among raft nodes before linearized reading' (duration: 219.897716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.272587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.215803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.274068Z","caller":"traceutil/trace.go:171","msg":"trace[2081920498] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"179.717669ms","start":"2026-02-28T01:30:27.094334Z","end":"2026-02-28T01:30:27.274052Z","steps":["trace[2081920498] 'agreement among raft nodes before linearized reading' (duration: 178.215044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.274266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.025785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.274299Z","caller":"traceutil/trace.go:171","msg":"trace[844976375] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"452.072187ms","start":"2026-02-28T01:30:26.822219Z","end":"2026-02-28T01:30:27.274291Z","steps":["trace[844976375] 'agreement among raft nodes before linearized reading' (duration: 451.281379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.274337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.822199Z","time spent":"452.127568ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.274526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.399794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.274552Z","caller":"traceutil/trace.go:171","msg":"trace[702591373] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"452.458126ms","start":"2026-02-28T01:30:26.822087Z","end":"2026-02-28T01:30:27.274545Z","steps":["trace[702591373] 'agreement among raft nodes before linearized reading' (duration: 452.401125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.274573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.822081Z","time spent":"452.486057ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.274711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.78543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.27474Z","caller":"traceutil/trace.go:171","msg":"trace[382930774] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"466.843541ms","start":"2026-02-28T01:30:26.807888Z","end":"2026-02-28T01:30:27.274731Z","steps":["trace[382930774] 'agreement among raft nodes before linearized reading' (duration: 466.78196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.274758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.669315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.27476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.80785Z","time spent":"466.904093ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:30:27.274801Z","caller":"traceutil/trace.go:171","msg":"trace[1545443933] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"299.748587ms","start":"2026-02-28T01:30:26.975043Z","end":"2026-02-28T01:30:27.274791Z","steps":["trace[1545443933] 'agreement among raft nodes before linearized reading' (duration: 299.680815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.055359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.275034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.145208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275058Z","caller":"traceutil/trace.go:171","msg":"trace[42371289] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"467.192149ms","start":"2026-02-28T01:30:26.807858Z","end":"2026-02-28T01:30:27.27505Z","steps":["trace[42371289] 'agreement among raft nodes before linearized reading' (duration: 467.151958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.807845Z","time spent":"467.22827ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.275089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.178412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275114Z","caller":"traceutil/trace.go:171","msg":"trace[1938872453] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"232.220162ms","start":"2026-02-28T01:30:27.042887Z","end":"2026-02-28T01:30:27.275107Z","steps":["trace[1938872453] 'agreement among raft nodes before linearized reading' (duration: 232.184431ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.273325Z","caller":"traceutil/trace.go:171","msg":"trace[2083205342] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"402.999433ms","start":"2026-02-28T01:30:26.870315Z","end":"2026-02-28T01:30:27.273314Z","steps":["trace[2083205342] 'agreement among raft nodes before linearized reading' (duration: 402.901891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.344801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.27543Z","caller":"traceutil/trace.go:171","msg":"trace[19865432] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1; }","duration":"107.387912ms","start":"2026-02-28T01:30:27.168026Z","end":"2026-02-28T01:30:27.275414Z","steps":["trace[19865432] 'agreement among raft nodes before linearized reading' (duration: 107.328991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.595028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275604Z","caller":"traceutil/trace.go:171","msg":"trace[1300575652] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1; }","duration":"107.652569ms","start":"2026-02-28T01:30:27.167945Z","end":"2026-02-28T01:30:27.275598Z","steps":["trace[1300575652] 'agreement among raft nodes before linearized reading' (duration: 107.611298ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.275053Z","caller":"traceutil/trace.go:171","msg":"trace[1774537751] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"232.11843ms","start":"2026-02-28T01:30:27.042926Z","end":"2026-02-28T01:30:27.275044Z","steps":["trace[1774537751] 'agreement among raft nodes before linearized reading' (duration: 232.060358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.430017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275681Z","caller":"traceutil/trace.go:171","msg":"trace[1235245776] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"241.492838ms","start":"2026-02-28T01:30:27.034179Z","end":"2026-02-28T01:30:27.275671Z","steps":["trace[1235245776] 'agreement among raft nodes before linearized reading' (duration: 241.416977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.805646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.275803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.234416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275811Z","caller":"traceutil/trace.go:171","msg":"trace[1270751279] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"241.841937ms","start":"2026-02-28T01:30:27.033963Z","end":"2026-02-28T01:30:27.275805Z","steps":["trace[1270751279] 'agreement among raft nodes before linearized reading' (duration: 241.808706ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.275825Z","caller":"traceutil/trace.go:171","msg":"trace[676389763] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1; }","duration":"118.274657ms","start":"2026-02-28T01:30:27.157544Z","end":"2026-02-28T01:30:27.275819Z","steps":["trace[676389763] 'agreement among raft nodes before linearized reading' (duration: 118.237756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.791212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.275963Z","caller":"traceutil/trace.go:171","msg":"trace[806648433] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"255.825223ms","start":"2026-02-28T01:30:27.02013Z","end":"2026-02-28T01:30:27.275955Z","steps":["trace[806648433] 'agreement among raft nodes before linearized reading' (duration: 255.777902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.276075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.264519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.276097Z","caller":"traceutil/trace.go:171","msg":"trace[1212499595] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"288.30886ms","start":"2026-02-28T01:30:26.987783Z","end":"2026-02-28T01:30:27.276091Z","steps":["trace[1212499595] 'agreement among raft nodes before linearized reading' (duration: 288.271689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.275895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.693619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.276207Z","caller":"traceutil/trace.go:171","msg":"trace[1003418732] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"256.013088ms","start":"2026-02-28T01:30:27.020185Z","end":"2026-02-28T01:30:27.276198Z","steps":["trace[1003418732] 'agreement among raft nodes before linearized reading' (duration: 255.68931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.276209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.414873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.276256Z","caller":"traceutil/trace.go:171","msg":"trace[1152311323] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"288.485674ms","start":"2026-02-28T01:30:26.987763Z","end":"2026-02-28T01:30:27.276248Z","steps":["trace[1152311323] 'agreement among raft nodes before linearized reading' (duration: 288.420902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.276646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.403421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.276668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.444513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.276674Z","caller":"traceutil/trace.go:171","msg":"trace[1976394112] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"324.464683ms","start":"2026-02-28T01:30:26.952202Z","end":"2026-02-28T01:30:27.276667Z","steps":["trace[1976394112] 'agreement among raft nodes before linearized reading' (duration: 324.418362ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.276689Z","caller":"traceutil/trace.go:171","msg":"trace[1126027049] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"315.487783ms","start":"2026-02-28T01:30:26.961195Z","end":"2026-02-28T01:30:27.276683Z","steps":["trace[1126027049] 'agreement among raft nodes before linearized reading' (duration: 315.441642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.276696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.952191Z","time spent":"324.498443ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.276708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.961188Z","time spent":"315.514715ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.276774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.870308Z","time spent":"405.096502ms","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.271869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.313254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.276869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.188563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.27869Z","caller":"traceutil/trace.go:171","msg":"trace[1751410484] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"304.027216ms","start":"2026-02-28T01:30:26.97465Z","end":"2026-02-28T01:30:27.278677Z","steps":["trace[1751410484] 'agreement among raft nodes before linearized reading' (duration: 302.196204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.278746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.974637Z","time spent":"304.091558ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.276871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.798571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.278846Z","caller":"traceutil/trace.go:171","msg":"trace[2016483406] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"317.772977ms","start":"2026-02-28T01:30:26.961049Z","end":"2026-02-28T01:30:27.278822Z","steps":["trace[2016483406] 'agreement among raft nodes before linearized reading' (duration: 315.801861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.278989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.96104Z","time spent":"317.894359ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.27696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.143698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.278475Z","caller":"traceutil/trace.go:171","msg":"trace[708347128] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"395.916858ms","start":"2026-02-28T01:30:26.882547Z","end":"2026-02-28T01:30:27.278464Z","steps":["trace[708347128] 'agreement among raft nodes before linearized reading' (duration: 389.301624ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.279043Z","caller":"traceutil/trace.go:171","msg":"trace[697046815] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"345.228537ms","start":"2026-02-28T01:30:26.933801Z","end":"2026-02-28T01:30:27.27903Z","steps":["trace[697046815] 'agreement among raft nodes before linearized reading' (duration: 343.140418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.279086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.933794Z","time spent":"345.280628ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.276998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.840891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.279226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.882527Z","time spent":"396.685416ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:30:27.279256Z","caller":"traceutil/trace.go:171","msg":"trace[891796083] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"330.128595ms","start":"2026-02-28T01:30:26.949112Z","end":"2026-02-28T01:30:27.279241Z","steps":["trace[891796083] 'agreement among raft nodes before linearized reading' (duration: 327.868892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.279294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.949088Z","time spent":"330.196366ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.277036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.806903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.279443Z","caller":"traceutil/trace.go:171","msg":"trace[625180566] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"485.260831ms","start":"2026-02-28T01:30:26.794166Z","end":"2026-02-28T01:30:27.279427Z","steps":["trace[625180566] 'agreement among raft nodes before linearized reading' (duration: 482.855085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.279481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.794151Z","time spent":"485.319572ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":28,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.271828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.896073Z","time spent":"375.747488ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.277262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.493936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.279761Z","caller":"traceutil/trace.go:171","msg":"trace[291872623] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"346.012455ms","start":"2026-02-28T01:30:26.933744Z","end":"2026-02-28T01:30:27.279756Z","steps":["trace[291872623] 'agreement among raft nodes before linearized reading' (duration: 343.496016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.279783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.933734Z","time spent":"346.038915ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.277296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.036072ms","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:4"} {"level":"info","ts":"2026-02-28T01:30:27.279872Z","caller":"traceutil/trace.go:171","msg":"trace[1379296688] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"359.615142ms","start":"2026-02-28T01:30:26.920249Z","end":"2026-02-28T01:30:27.279864Z","steps":["trace[1379296688] 'agreement among raft nodes before linearized reading' (duration: 357.033882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.279889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.920244Z","time spent":"359.640242ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:30:27.28389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.428075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.283998Z","caller":"traceutil/trace.go:171","msg":"trace[85050985] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"363.747008ms","start":"2026-02-28T01:30:26.920195Z","end":"2026-02-28T01:30:27.283942Z","steps":["trace[85050985] 'agreement among raft nodes before linearized reading' (duration: 358.411974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.284063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:26.920189Z","time spent":"363.84086ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-28T01:30:27.619897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.637539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.619941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.386181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.619961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.365781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.62004Z","caller":"traceutil/trace.go:171","msg":"trace[1266553851] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1; }","duration":"176.530774ms","start":"2026-02-28T01:30:27.443482Z","end":"2026-02-28T01:30:27.620013Z","steps":["trace[1266553851] 'count revisions from in-memory index tree' (duration: 176.30965ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.620055Z","caller":"traceutil/trace.go:171","msg":"trace[398049676] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1; }","duration":"164.492004ms","start":"2026-02-28T01:30:27.45554Z","end":"2026-02-28T01:30:27.620032Z","steps":["trace[398049676] 'range keys from in-memory index tree' (duration: 164.253588ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.620009Z","caller":"traceutil/trace.go:171","msg":"trace[107543720] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1; }","duration":"201.815364ms","start":"2026-02-28T01:30:27.418172Z","end":"2026-02-28T01:30:27.619987Z","steps":["trace[107543720] 'range keys from in-memory index tree' (duration: 201.499237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.908431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.620385Z","caller":"traceutil/trace.go:171","msg":"trace[393381131] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"154.981353ms","start":"2026-02-28T01:30:27.465394Z","end":"2026-02-28T01:30:27.620375Z","steps":["trace[393381131] 'count revisions from in-memory index tree' (duration: 154.86594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.796361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.620405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.436375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.62044Z","caller":"traceutil/trace.go:171","msg":"trace[1191614165] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1; }","duration":"164.847263ms","start":"2026-02-28T01:30:27.455577Z","end":"2026-02-28T01:30:27.620424Z","steps":["trace[1191614165] 'count revisions from in-memory index tree' (duration: 164.763441ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.62046Z","caller":"traceutil/trace.go:171","msg":"trace[1147670021] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1; }","duration":"192.516257ms","start":"2026-02-28T01:30:27.42793Z","end":"2026-02-28T01:30:27.620446Z","steps":["trace[1147670021] 'count revisions from in-memory index tree' (duration: 192.357303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.974041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.620533Z","caller":"traceutil/trace.go:171","msg":"trace[159952088] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1; }","duration":"230.072253ms","start":"2026-02-28T01:30:27.390448Z","end":"2026-02-28T01:30:27.620521Z","steps":["trace[159952088] 'count revisions from in-memory index tree' (duration: 229.886399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.430245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.620658Z","caller":"traceutil/trace.go:171","msg":"trace[1850610785] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1; }","duration":"192.487986ms","start":"2026-02-28T01:30:27.428161Z","end":"2026-02-28T01:30:27.620649Z","steps":["trace[1850610785] 'range keys from in-memory index tree' (duration: 192.348533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.763759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.620846Z","caller":"traceutil/trace.go:171","msg":"trace[1119695135] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1; }","duration":"243.296481ms","start":"2026-02-28T01:30:27.377539Z","end":"2026-02-28T01:30:27.620836Z","steps":["trace[1119695135] 'count revisions from in-memory index tree' (duration: 242.706098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.492018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:30:27.620839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.39035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.620891Z","caller":"traceutil/trace.go:171","msg":"trace[1157799693] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1; }","duration":"177.533229ms","start":"2026-02-28T01:30:27.443344Z","end":"2026-02-28T01:30:27.620877Z","steps":["trace[1157799693] 'range keys from in-memory index tree' (duration: 177.437825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.652264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.62096Z","caller":"traceutil/trace.go:171","msg":"trace[742391324] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1; }","duration":"202.706915ms","start":"2026-02-28T01:30:27.418241Z","end":"2026-02-28T01:30:27.620948Z","steps":["trace[742391324] 'count revisions from in-memory index tree' (duration: 202.594222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.620387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.259707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.621051Z","caller":"traceutil/trace.go:171","msg":"trace[1203554949] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1; }","duration":"242.935872ms","start":"2026-02-28T01:30:27.378104Z","end":"2026-02-28T01:30:27.62104Z","steps":["trace[1203554949] 'range keys from in-memory index tree' (duration: 242.225556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.621123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.212083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.621157Z","caller":"traceutil/trace.go:171","msg":"trace[937657666] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1; }","duration":"217.263174ms","start":"2026-02-28T01:30:27.403885Z","end":"2026-02-28T01:30:27.621149Z","steps":["trace[937657666] 'range keys from in-memory index tree' (duration: 217.165782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:27.621293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.329885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:30:27.621335Z","caller":"traceutil/trace.go:171","msg":"trace[1228462347] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1; }","duration":"217.383687ms","start":"2026-02-28T01:30:27.403935Z","end":"2026-02-28T01:30:27.621319Z","steps":["trace[1228462347] 'count revisions from in-memory index tree' (duration: 217.257014ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:27.620914Z","caller":"traceutil/trace.go:171","msg":"trace[1069869026] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1; }","duration":"230.505943ms","start":"2026-02-28T01:30:27.390396Z","end":"2026-02-28T01:30:27.620902Z","steps":["trace[1069869026] 'range keys from in-memory index tree' (duration: 230.252717ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:55.744356Z","caller":"traceutil/trace.go:171","msg":"trace[799939955] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"235.517939ms","start":"2026-02-28T01:30:55.508813Z","end":"2026-02-28T01:30:55.744331Z","steps":["trace[799939955] 'process raft request' (duration: 235.365396ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:02.045659Z","caller":"traceutil/trace.go:171","msg":"trace[612478586] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"267.322481ms","start":"2026-02-28T01:31:01.778302Z","end":"2026-02-28T01:31:02.045625Z","steps":["trace[612478586] 'process raft request' (duration: 267.048215ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:02.439918Z","caller":"traceutil/trace.go:171","msg":"trace[481077858] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"297.218347ms","start":"2026-02-28T01:31:02.142665Z","end":"2026-02-28T01:31:02.439884Z","steps":["trace[481077858] 'read index received' (duration: 296.9205ms)","trace[481077858] 'applied index is now lower than readState.Index' (duration: 296.047µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:02.440092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.385812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:02.440136Z","caller":"traceutil/trace.go:171","msg":"trace[1451621996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"297.490634ms","start":"2026-02-28T01:31:02.142634Z","end":"2026-02-28T01:31:02.440124Z","steps":["trace[1451621996] 'agreement among raft nodes before linearized reading' (duration: 297.376101ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:02.440473Z","caller":"traceutil/trace.go:171","msg":"trace[673454772] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"375.173715ms","start":"2026-02-28T01:31:02.065221Z","end":"2026-02-28T01:31:02.440395Z","steps":["trace[673454772] 'process raft request' (duration: 374.416267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:02.441142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:02.065199Z","time spent":"375.359099ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:03.289986Z","caller":"traceutil/trace.go:171","msg":"trace[132423714] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"677.505071ms","start":"2026-02-28T01:31:02.612446Z","end":"2026-02-28T01:31:03.289951Z","steps":["trace[132423714] 'process raft request' (duration: 676.922978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:03.290156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:02.612414Z","time spent":"677.673006ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:03.29078Z","caller":"traceutil/trace.go:171","msg":"trace[118966049] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"632.805209ms","start":"2026-02-28T01:31:02.657947Z","end":"2026-02-28T01:31:03.290752Z","steps":["trace[118966049] 'read index received' (duration: 26.899897ms)","trace[118966049] 'applied index is now lower than readState.Index' (duration: 605.903892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:03.291108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.157358ms","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-02-28T01:31:03.291153Z","caller":"traceutil/trace.go:171","msg":"trace[1406655265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"633.25514ms","start":"2026-02-28T01:31:02.657886Z","end":"2026-02-28T01:31:03.291141Z","steps":["trace[1406655265] 'agreement among raft nodes before linearized reading' (duration: 633.107717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:03.291183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:02.657864Z","time spent":"633.313841ms","remote":"127.0.0.1:55074","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-02-28T01:31:03.291199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.038078ms","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-02-28T01:31:03.291249Z","caller":"traceutil/trace.go:171","msg":"trace[1875587618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"631.11352ms","start":"2026-02-28T01:31:02.66012Z","end":"2026-02-28T01:31:03.291234Z","steps":["trace[1875587618] 'agreement among raft nodes before linearized reading' (duration: 630.954227ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:03.291293Z","caller":"traceutil/trace.go:171","msg":"trace[1278113953] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"638.567064ms","start":"2026-02-28T01:31:02.652717Z","end":"2026-02-28T01:31:03.291284Z","steps":["trace[1278113953] 'process raft request' (duration: 637.13632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:03.291342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:02.652687Z","time spent":"638.630165ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:03.291291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:02.660109Z","time spent":"631.173621ms","remote":"127.0.0.1:55074","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-02-28T01:31:04.484135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.981725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-28T01:31:04.484165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.146145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:04.484223Z","caller":"traceutil/trace.go:171","msg":"trace[520716041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"434.130498ms","start":"2026-02-28T01:31:04.050069Z","end":"2026-02-28T01:31:04.4842Z","steps":["trace[520716041] 'range keys from in-memory index tree' (duration: 433.708958ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:04.484242Z","caller":"traceutil/trace.go:171","msg":"trace[339492867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"342.585486ms","start":"2026-02-28T01:31:04.141636Z","end":"2026-02-28T01:31:04.484222Z","steps":["trace[339492867] 'range keys from in-memory index tree' (duration: 342.023882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:04.484268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:04.050041Z","time spent":"434.217851ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T01:31:04.484288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:04.14161Z","time spent":"342.666997ms","remote":"127.0.0.1:54794","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-02-28T01:31:05.099346Z","caller":"traceutil/trace.go:171","msg":"trace[1002454756] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"609.561688ms","start":"2026-02-28T01:31:04.489761Z","end":"2026-02-28T01:31:05.099323Z","steps":["trace[1002454756] 'process raft request' (duration: 609.417084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:05.099485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:04.489729Z","time spent":"609.682851ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:05.324214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.605736ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-28T01:31:05.32433Z","caller":"traceutil/trace.go:171","msg":"trace[1487187498] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"181.995361ms","start":"2026-02-28T01:31:05.142317Z","end":"2026-02-28T01:31:05.324312Z","steps":["trace[1487187498] 'read index received' (duration: 2.111279ms)","trace[1487187498] 'applied index is now lower than readState.Index' (duration: 179.882762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:05.324456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.125984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:05.324491Z","caller":"traceutil/trace.go:171","msg":"trace[14702779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"182.190786ms","start":"2026-02-28T01:31:05.142291Z","end":"2026-02-28T01:31:05.324482Z","steps":["trace[14702779] 'agreement among raft nodes before linearized reading' (duration: 182.095394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:06.346427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.916546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:06.346517Z","caller":"traceutil/trace.go:171","msg":"trace[1522657489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"205.06167ms","start":"2026-02-28T01:31:06.141434Z","end":"2026-02-28T01:31:06.346496Z","steps":["trace[1522657489] 'range keys from in-memory index tree' (duration: 204.849175ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:10.34973Z","caller":"traceutil/trace.go:171","msg":"trace[1554950334] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"125.525676ms","start":"2026-02-28T01:31:10.224171Z","end":"2026-02-28T01:31:10.349696Z","steps":["trace[1554950334] 'process raft request' (duration: 125.019284ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:25.223141Z","caller":"traceutil/trace.go:171","msg":"trace[1506186533] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"322.177699ms","start":"2026-02-28T01:31:24.900948Z","end":"2026-02-28T01:31:25.223126Z","steps":["trace[1506186533] 'process raft request' (duration: 322.074896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:25.223248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:24.900937Z","time spent":"322.261221ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:26.485788Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.26274269s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-28T01:31:26.53346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391317687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:26.533541Z","caller":"traceutil/trace.go:171","msg":"trace[1379580277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.391405869s","start":"2026-02-28T01:31:25.142121Z","end":"2026-02-28T01:31:26.533526Z","steps":["trace[1379580277] 'agreement among raft nodes before linearized reading' (duration: 1.391279326s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.533567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:25.142073Z","time spent":"1.391489442s","remote":"127.0.0.1:54788","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-02-28T01:31:26.533588Z","caller":"traceutil/trace.go:171","msg":"trace[1148409176] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"1.391264356s","start":"2026-02-28T01:31:25.142126Z","end":"2026-02-28T01:31:26.53339Z","steps":["trace[1148409176] 'read index received' (duration: 80.981217ms)","trace[1148409176] 'applied index is now lower than readState.Index' (duration: 1.310282109s)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:26.533842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036905128s","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-02-28T01:31:26.533866Z","caller":"traceutil/trace.go:171","msg":"trace[1689321008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.036958988s","start":"2026-02-28T01:31:25.496901Z","end":"2026-02-28T01:31:26.53386Z","steps":["trace[1689321008] 'agreement among raft nodes before linearized reading' (duration: 1.036863746s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.53388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:25.496886Z","time spent":"1.036990549s","remote":"127.0.0.1:55074","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-02-28T01:31:26.533923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046958822s","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-02-28T01:31:26.534218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096752872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:31:26.53426Z","caller":"traceutil/trace.go:171","msg":"trace[2044441150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.0473314s","start":"2026-02-28T01:31:25.486912Z","end":"2026-02-28T01:31:26.534243Z","steps":["trace[2044441150] 'agreement among raft nodes before linearized reading' (duration: 1.046870789s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.534309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:25.486901Z","time spent":"1.047395031s","remote":"127.0.0.1:55074","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-02-28T01:31:26.53431Z","caller":"traceutil/trace.go:171","msg":"trace[1111644277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"1.096884715s","start":"2026-02-28T01:31:25.437398Z","end":"2026-02-28T01:31:26.534282Z","steps":["trace[1111644277] 'agreement among raft nodes before linearized reading' (duration: 1.096398524s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.534107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264234095s","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-02-28T01:31:26.53437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:25.437364Z","time spent":"1.096991388s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-28T01:31:26.534422Z","caller":"traceutil/trace.go:171","msg":"trace[1241342541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"1.264564883s","start":"2026-02-28T01:31:25.269817Z","end":"2026-02-28T01:31:26.534382Z","steps":["trace[1241342541] 'agreement among raft nodes before linearized reading' (duration: 1.263933809s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.534559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:25.2698Z","time spent":"1.264715577s","remote":"127.0.0.1:55074","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-02-28T01:31:26.796364Z","caller":"traceutil/trace.go:171","msg":"trace[999989887] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"258.204738ms","start":"2026-02-28T01:31:26.538103Z","end":"2026-02-28T01:31:26.796307Z","steps":["trace[999989887] 'process raft request' (duration: 255.660389ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:26.947475Z","caller":"traceutil/trace.go:171","msg":"trace[45551057] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"409.15237ms","start":"2026-02-28T01:31:26.538295Z","end":"2026-02-28T01:31:26.947448Z","steps":["trace[45551057] 'process raft request' (duration: 409.071218ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:26.947607Z","caller":"traceutil/trace.go:171","msg":"trace[2117704781] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"409.255703ms","start":"2026-02-28T01:31:26.538247Z","end":"2026-02-28T01:31:26.947503Z","steps":["trace[2117704781] 'process raft request' (duration: 387.686492ms)","trace[2117704781] 'compare' (duration: 21.264773ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:26.947669Z","caller":"traceutil/trace.go:171","msg":"trace[362785437] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"405.916405ms","start":"2026-02-28T01:31:26.541704Z","end":"2026-02-28T01:31:26.947621Z","steps":["trace[362785437] 'read index received' (duration: 252.104756ms)","trace[362785437] 'applied index is now lower than readState.Index' (duration: 153.809259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:26.947708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:26.53829Z","time spent":"409.324414ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:26.947727Z","caller":"traceutil/trace.go:171","msg":"trace[2026600488] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"409.277073ms","start":"2026-02-28T01:31:26.538421Z","end":"2026-02-28T01:31:26.947698Z","steps":["trace[2026600488] 'process raft request' (duration: 408.986746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.947821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.091429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:26.947858Z","caller":"traceutil/trace.go:171","msg":"trace[776189295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"406.169022ms","start":"2026-02-28T01:31:26.541677Z","end":"2026-02-28T01:31:26.947846Z","steps":["trace[776189295] 'agreement among raft nodes before linearized reading' (duration: 406.07197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:26.947871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:26.538415Z","time spent":"409.381357ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:26.947884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:26.538237Z","time spent":"409.499079ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:26.947893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:26.541662Z","time spent":"406.223133ms","remote":"127.0.0.1:54794","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-02-28T01:31:29.400999Z","caller":"traceutil/trace.go:171","msg":"trace[421521329] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"445.185801ms","start":"2026-02-28T01:31:28.955787Z","end":"2026-02-28T01:31:29.400973Z","steps":["trace[421521329] 'process raft request' (duration: 444.998707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:29.401172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:28.95577Z","time spent":"445.309484ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:29.73917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.388843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:29.739394Z","caller":"traceutil/trace.go:171","msg":"trace[1579437135] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:716; }","duration":"502.871505ms","start":"2026-02-28T01:31:29.236497Z","end":"2026-02-28T01:31:29.739369Z","steps":["trace[1579437135] 'read index received' (duration: 164.192385ms)","trace[1579437135] 'applied index is now lower than readState.Index' (duration: 338.67826ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:29.739391Z","caller":"traceutil/trace.go:171","msg":"trace[1604357209] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"783.236186ms","start":"2026-02-28T01:31:28.956126Z","end":"2026-02-28T01:31:29.739362Z","steps":["trace[1604357209] 'process raft request' (duration: 497.571136ms)","trace[1604357209] 'compare' (duration: 285.283281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:29.739535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.03407ms","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-02-28T01:31:29.739565Z","caller":"traceutil/trace.go:171","msg":"trace[1970649018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"503.098311ms","start":"2026-02-28T01:31:29.236457Z","end":"2026-02-28T01:31:29.739555Z","steps":["trace[1970649018] 'agreement among raft nodes before linearized reading' (duration: 502.974318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:29.739597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:29.236437Z","time spent":"503.152403ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-28T01:31:29.73961Z","caller":"traceutil/trace.go:171","msg":"trace[718355579] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"783.159364ms","start":"2026-02-28T01:31:28.95643Z","end":"2026-02-28T01:31:29.739589Z","steps":["trace[718355579] 'process raft request' (duration: 782.844156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:29.73975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:28.956418Z","time spent":"783.271097ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:29.740374Z","caller":"traceutil/trace.go:171","msg":"trace[575403772] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"550.548387ms","start":"2026-02-28T01:31:29.189798Z","end":"2026-02-28T01:31:29.740346Z","steps":["trace[575403772] 'process raft request' (duration: 549.528302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:29.740472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:29.189774Z","time spent":"550.64464ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:29.740493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:28.956108Z","time spent":"783.348168ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:30.174395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.964009ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-28T01:31:30.17473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:29.833877Z","time spent":"340.839362ms","remote":"127.0.0.1:54842","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-28T01:31:57.773938Z","caller":"traceutil/trace.go:171","msg":"trace[371554538] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"177.699571ms","start":"2026-02-28T01:31:57.596209Z","end":"2026-02-28T01:31:57.773908Z","steps":["trace[371554538] 'process raft request' (duration: 177.476865ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:59.267807Z","caller":"traceutil/trace.go:171","msg":"trace[1955229237] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"146.337914ms","start":"2026-02-28T01:31:59.121441Z","end":"2026-02-28T01:31:59.267779Z","steps":["trace[1955229237] 'process raft request' (duration: 146.180671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.171253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.371435ms","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-02-28T01:32:00.17126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.784234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-28T01:32:00.171274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.350747ms","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-02-28T01:32:00.171322Z","caller":"traceutil/trace.go:171","msg":"trace[342781060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"110.495718ms","start":"2026-02-28T01:32:00.060812Z","end":"2026-02-28T01:32:00.171307Z","steps":["trace[342781060] 'range keys from in-memory index tree' (duration: 110.199552ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:00.171342Z","caller":"traceutil/trace.go:171","msg":"trace[1721658189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"295.882407ms","start":"2026-02-28T01:31:59.875437Z","end":"2026-02-28T01:32:00.171319Z","steps":["trace[1721658189] 'range keys from in-memory index tree' (duration: 295.62092ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:00.171345Z","caller":"traceutil/trace.go:171","msg":"trace[776580722] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:781; }","duration":"344.47069ms","start":"2026-02-28T01:31:59.826858Z","end":"2026-02-28T01:32:00.171329Z","steps":["trace[776580722] 'range keys from in-memory index tree' (duration: 344.173582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.171388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:59.826835Z","time spent":"344.544761ms","remote":"127.0.0.1:54842","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-02-28T01:32:00.171551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.745013ms","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-02-28T01:32:00.171669Z","caller":"traceutil/trace.go:171","msg":"trace[480160113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"295.890586ms","start":"2026-02-28T01:31:59.875748Z","end":"2026-02-28T01:32:00.171638Z","steps":["trace[480160113] 'range keys from in-memory index tree' (duration: 295.540298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.17111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.376841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:32:00.172001Z","caller":"traceutil/trace.go:171","msg":"trace[1307840481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"394.322334ms","start":"2026-02-28T01:31:59.777663Z","end":"2026-02-28T01:32:00.171856Z","steps":["trace[1307840481] 'range keys from in-memory index tree' (duration: 393.202387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.17225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:59.777637Z","time spent":"394.594331ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-28T01:32:00.51904Z","caller":"traceutil/trace.go:171","msg":"trace[103651713] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"345.929475ms","start":"2026-02-28T01:32:00.173079Z","end":"2026-02-28T01:32:00.519009Z","steps":["trace[103651713] 'read index received' (duration: 345.883464ms)","trace[103651713] 'applied index is now lower than readState.Index' (duration: 44.281µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:00.519041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.172359Z","time spent":"346.676803ms","remote":"127.0.0.1:54842","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-28T01:32:00.51935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.089629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:00.519414Z","caller":"traceutil/trace.go:171","msg":"trace[212447469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"346.364955ms","start":"2026-02-28T01:32:00.17303Z","end":"2026-02-28T01:32:00.519395Z","steps":["trace[212447469] 'agreement among raft nodes before linearized reading' (duration: 346.076808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.519463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.172997Z","time spent":"346.454038ms","remote":"127.0.0.1:54788","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-02-28T01:32:00.641542Z","caller":"traceutil/trace.go:171","msg":"trace[1594101691] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"464.232542ms","start":"2026-02-28T01:32:00.177282Z","end":"2026-02-28T01:32:00.641515Z","steps":["trace[1594101691] 'process raft request' (duration: 403.518985ms)","trace[1594101691] 'compare' (duration: 60.469501ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:00.641607Z","caller":"traceutil/trace.go:171","msg":"trace[558185949] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"464.290733ms","start":"2026-02-28T01:32:00.177296Z","end":"2026-02-28T01:32:00.641587Z","steps":["trace[558185949] 'process raft request' (duration: 464.093309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.641698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.177252Z","time spent":"464.367565ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:00.641729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.177276Z","time spent":"464.379415ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:00.642061Z","caller":"traceutil/trace.go:171","msg":"trace[2128193099] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"464.626342ms","start":"2026-02-28T01:32:00.177393Z","end":"2026-02-28T01:32:00.64202Z","steps":["trace[2128193099] 'process raft request' (duration: 464.438617ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:00.642206Z","caller":"traceutil/trace.go:171","msg":"trace[657947553] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"464.543509ms","start":"2026-02-28T01:32:00.177653Z","end":"2026-02-28T01:32:00.642197Z","steps":["trace[657947553] 'process raft request' (duration: 464.325804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.642265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.177645Z","time spent":"464.59115ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:00.642607Z","caller":"traceutil/trace.go:171","msg":"trace[739654689] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"121.649238ms","start":"2026-02-28T01:32:00.520916Z","end":"2026-02-28T01:32:00.642565Z","steps":["trace[739654689] 'process raft request' (duration: 121.190357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.642157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:00.177375Z","time spent":"464.747734ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:00.72871Z","caller":"traceutil/trace.go:171","msg":"trace[1156989646] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:814; }","duration":"119.620939ms","start":"2026-02-28T01:32:00.609066Z","end":"2026-02-28T01:32:00.728687Z","steps":["trace[1156989646] 'read index received' (duration: 20.996417ms)","trace[1156989646] 'applied index is now lower than readState.Index' (duration: 98.623722ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:00.728815Z","caller":"traceutil/trace.go:171","msg":"trace[1133075033] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"126.190498ms","start":"2026-02-28T01:32:00.602594Z","end":"2026-02-28T01:32:00.728785Z","steps":["trace[1133075033] 'process raft request' (duration: 125.972532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:00.728942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.833064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:00.729027Z","caller":"traceutil/trace.go:171","msg":"trace[1044854620] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:787; }","duration":"120.000888ms","start":"2026-02-28T01:32:00.609007Z","end":"2026-02-28T01:32:00.729008Z","steps":["trace[1044854620] 'agreement among raft nodes before linearized reading' (duration: 119.793333ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:02.834046Z","caller":"traceutil/trace.go:171","msg":"trace[1808990916] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"183.43201ms","start":"2026-02-28T01:32:02.650592Z","end":"2026-02-28T01:32:02.834024Z","steps":["trace[1808990916] 'process raft request' (duration: 183.237135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.023489Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043936463s","expected-duration":"1s"} {"level":"info","ts":"2026-02-28T01:32:04.02414Z","caller":"traceutil/trace.go:171","msg":"trace[1660534937] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.373066232s","start":"2026-02-28T01:32:02.651045Z","end":"2026-02-28T01:32:04.024111Z","steps":["trace[1660534937] 'process raft request' (duration: 1.372794646s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.024324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:02.651025Z","time spent":"1.373206206s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:04.02448Z","caller":"traceutil/trace.go:171","msg":"trace[1103249188] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"1.371101735s","start":"2026-02-28T01:32:02.653347Z","end":"2026-02-28T01:32:04.024449Z","steps":["trace[1103249188] 'read index received' (duration: 181.565285ms)","trace[1103249188] 'applied index is now lower than readState.Index' (duration: 1.18952967s)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:04.024594Z","caller":"traceutil/trace.go:171","msg":"trace[661945827] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.373171546s","start":"2026-02-28T01:32:02.651413Z","end":"2026-02-28T01:32:04.024585Z","steps":["trace[661945827] 'process raft request' (duration: 1.372656593s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.024706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:02.651404Z","time spent":"1.373218366s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:04.024783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371423043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:32:04.024854Z","caller":"traceutil/trace.go:171","msg":"trace[523201941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"1.371525626s","start":"2026-02-28T01:32:02.65331Z","end":"2026-02-28T01:32:04.024836Z","steps":["trace[523201941] 'agreement among raft nodes before linearized reading' (duration: 1.371328151s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.024895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:02.653298Z","time spent":"1.371588357s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T01:32:04.521843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.187362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:04.52201Z","caller":"traceutil/trace.go:171","msg":"trace[397073537] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"467.199404ms","start":"2026-02-28T01:32:04.054794Z","end":"2026-02-28T01:32:04.521993Z","steps":["trace[397073537] 'process raft request' (duration: 467.139782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.522071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:04.054773Z","time spent":"467.269485ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:04.522104Z","caller":"traceutil/trace.go:171","msg":"trace[2098538016] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"574.509715ms","start":"2026-02-28T01:32:03.947545Z","end":"2026-02-28T01:32:04.522055Z","steps":["trace[2098538016] 'process raft request' (duration: 170.021896ms)","trace[2098538016] 'compare' (duration: 404.09733ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:04.522187Z","caller":"traceutil/trace.go:171","msg":"trace[244085881] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"497.588068ms","start":"2026-02-28T01:32:04.02454Z","end":"2026-02-28T01:32:04.522128Z","steps":["trace[244085881] 'read index received' (duration: 93.044728ms)","trace[244085881] 'applied index is now lower than readState.Index' (duration: 404.53381ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:04.522252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:03.947515Z","time spent":"574.658429ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:04.522378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0607825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:04.522447Z","caller":"traceutil/trace.go:171","msg":"trace[1293468776] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:793; }","duration":"1.060879812s","start":"2026-02-28T01:32:03.461554Z","end":"2026-02-28T01:32:04.522434Z","steps":["trace[1293468776] 'agreement among raft nodes before linearized reading' (duration: 1.06078091s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.522561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:04.522666Z","caller":"traceutil/trace.go:171","msg":"trace[1756341677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"383.136983ms","start":"2026-02-28T01:32:04.139506Z","end":"2026-02-28T01:32:04.522643Z","steps":["trace[1756341677] 'agreement among raft nodes before linearized reading' (duration: 382.896598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.522616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233703087s","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-02-28T01:32:04.522748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:03.461529Z","time spent":"1.061000236s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:32:04.522777Z","caller":"traceutil/trace.go:171","msg":"trace[1601364616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.233877901s","start":"2026-02-28T01:32:03.288866Z","end":"2026-02-28T01:32:04.522744Z","steps":["trace[1601364616] 'agreement among raft nodes before linearized reading' (duration: 1.233493852s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:04.52286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:03.288802Z","time spent":"1.234041994s","remote":"127.0.0.1:55074","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-02-28T01:32:04.522733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:04.139473Z","time spent":"383.249385ms","remote":"127.0.0.1:54794","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-02-28T01:32:07.278212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.610862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:07.278324Z","caller":"traceutil/trace.go:171","msg":"trace[1926349806] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"520.631744ms","start":"2026-02-28T01:32:06.757674Z","end":"2026-02-28T01:32:07.278306Z","steps":["trace[1926349806] 'read index received' (duration: 59.391µs)","trace[1926349806] 'applied index is now lower than readState.Index' (duration: 520.571203ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:07.27845Z","caller":"traceutil/trace.go:171","msg":"trace[1459953021] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"638.977152ms","start":"2026-02-28T01:32:06.639444Z","end":"2026-02-28T01:32:07.278421Z","steps":["trace[1459953021] 'process raft request' (duration: 39.036842ms)","trace[1459953021] 'compare' (duration: 599.445038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:07.278517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:06.639418Z","time spent":"639.068705ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:07.278623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.574882ms","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-02-28T01:32:07.278717Z","caller":"traceutil/trace.go:171","msg":"trace[1485397359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"373.696185ms","start":"2026-02-28T01:32:06.904989Z","end":"2026-02-28T01:32:07.278686Z","steps":["trace[1485397359] 'agreement among raft nodes before linearized reading' (duration: 373.4571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:07.278797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:06.90497Z","time spent":"373.813848ms","remote":"127.0.0.1:55074","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-02-28T01:32:07.278963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.005629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:07.279025Z","caller":"traceutil/trace.go:171","msg":"trace[158137598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"137.118111ms","start":"2026-02-28T01:32:07.141894Z","end":"2026-02-28T01:32:07.279012Z","steps":["trace[158137598] 'agreement among raft nodes before linearized reading' (duration: 137.034929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:07.278969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.297171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:07.279733Z","caller":"traceutil/trace.go:171","msg":"trace[731125721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"522.0998ms","start":"2026-02-28T01:32:06.75761Z","end":"2026-02-28T01:32:07.27971Z","steps":["trace[731125721] 'agreement among raft nodes before linearized reading' (duration: 521.308311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:07.279901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:06.757579Z","time spent":"522.307524ms","remote":"127.0.0.1:54794","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-02-28T01:32:07.616485Z","caller":"traceutil/trace.go:171","msg":"trace[93225119] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"240.115609ms","start":"2026-02-28T01:32:07.376338Z","end":"2026-02-28T01:32:07.616453Z","steps":["trace[93225119] 'read index received' (duration: 171.80888ms)","trace[93225119] 'applied index is now lower than readState.Index' (duration: 68.305669ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:07.616583Z","caller":"traceutil/trace.go:171","msg":"trace[1565529932] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"332.529932ms","start":"2026-02-28T01:32:07.28402Z","end":"2026-02-28T01:32:07.61655Z","steps":["trace[1565529932] 'process raft request' (duration: 264.12609ms)","trace[1565529932] 'compare' (duration: 68.150886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:07.616669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.311064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:32:07.616774Z","caller":"traceutil/trace.go:171","msg":"trace[1403018319] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:800; }","duration":"240.489149ms","start":"2026-02-28T01:32:07.376265Z","end":"2026-02-28T01:32:07.616754Z","steps":["trace[1403018319] 'agreement among raft nodes before linearized reading' (duration: 240.329855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:07.616786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:07.284001Z","time spent":"332.652144ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:08.312214Z","caller":"traceutil/trace.go:171","msg":"trace[768808948] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"102.119577ms","start":"2026-02-28T01:32:08.21007Z","end":"2026-02-28T01:32:08.312189Z","steps":["trace[768808948] 'process raft request' (duration: 100.838046ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:20.670438Z","caller":"traceutil/trace.go:171","msg":"trace[1864725501] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"223.122869ms","start":"2026-02-28T01:32:20.447291Z","end":"2026-02-28T01:32:20.670414Z","steps":["trace[1864725501] 'read index received' (duration: 222.932625ms)","trace[1864725501] 'applied index is now lower than readState.Index' (duration: 189.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:20.670553Z","caller":"traceutil/trace.go:171","msg":"trace[295559632] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"397.497471ms","start":"2026-02-28T01:32:20.27302Z","end":"2026-02-28T01:32:20.670517Z","steps":["trace[295559632] 'process raft request' (duration: 397.266535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:20.670624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.323224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-28T01:32:20.67065Z","caller":"traceutil/trace.go:171","msg":"trace[387709665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"223.409816ms","start":"2026-02-28T01:32:20.447233Z","end":"2026-02-28T01:32:20.670642Z","steps":["trace[387709665] 'agreement among raft nodes before linearized reading' (duration: 223.282033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:20.67073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:20.273003Z","time spent":"397.637464ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:20.992484Z","caller":"traceutil/trace.go:171","msg":"trace[1761012559] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"274.050309ms","start":"2026-02-28T01:32:20.71841Z","end":"2026-02-28T01:32:20.99246Z","steps":["trace[1761012559] 'read index received' (duration: 225.486486ms)","trace[1761012559] 'applied index is now lower than readState.Index' (duration: 48.562623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:20.99271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.278015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:32:20.99279Z","caller":"traceutil/trace.go:171","msg":"trace[217299312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"274.397787ms","start":"2026-02-28T01:32:20.718381Z","end":"2026-02-28T01:32:20.992779Z","steps":["trace[217299312] 'agreement among raft nodes before linearized reading' (duration: 274.169562ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:20.992944Z","caller":"traceutil/trace.go:171","msg":"trace[1391233201] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"316.890464ms","start":"2026-02-28T01:32:20.676027Z","end":"2026-02-28T01:32:20.992917Z","steps":["trace[1391233201] 'process raft request' (duration: 267.946711ms)","trace[1391233201] 'compare' (duration: 48.123533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:20.993094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:20.675992Z","time spent":"317.017467ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:23.11856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.25307ms","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-02-28T01:32:23.118633Z","caller":"traceutil/trace.go:171","msg":"trace[987145440] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:890; }","duration":"319.390893ms","start":"2026-02-28T01:32:22.799225Z","end":"2026-02-28T01:32:23.118616Z","steps":["trace[987145440] 'count revisions from in-memory index tree' (duration: 319.153307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.118667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:22.799204Z","time spent":"319.455004ms","remote":"127.0.0.1:55302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:32:23.118848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.695365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-28T01:32:23.11898Z","caller":"traceutil/trace.go:171","msg":"trace[1470839792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"120.870629ms","start":"2026-02-28T01:32:22.998079Z","end":"2026-02-28T01:32:23.118949Z","steps":["trace[1470839792] 'range keys from in-memory index tree' (duration: 120.402238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.118965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.715499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:23.119089Z","caller":"traceutil/trace.go:171","msg":"trace[1097858647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"976.892263ms","start":"2026-02-28T01:32:22.14217Z","end":"2026-02-28T01:32:23.119062Z","steps":["trace[1097858647] 'range keys from in-memory index tree' (duration: 976.629777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.119144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:22.142152Z","time spent":"976.981245ms","remote":"127.0.0.1:54794","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-02-28T01:32:23.1192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.69298ms","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-02-28T01:32:23.119248Z","caller":"traceutil/trace.go:171","msg":"trace[1337363296] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:890; }","duration":"938.741122ms","start":"2026-02-28T01:32:22.1805Z","end":"2026-02-28T01:32:23.119241Z","steps":["trace[1337363296] 'range keys from in-memory index tree' (duration: 938.68439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.119201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.948867ms","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-02-28T01:32:23.119236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352129226s","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-02-28T01:32:23.119312Z","caller":"traceutil/trace.go:171","msg":"trace[1633620602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"443.712956ms","start":"2026-02-28T01:32:22.675572Z","end":"2026-02-28T01:32:23.119285Z","steps":["trace[1633620602] 'range keys from in-memory index tree' (duration: 442.762373ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:23.119319Z","caller":"traceutil/trace.go:171","msg":"trace[617780722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"1.352242329s","start":"2026-02-28T01:32:21.767054Z","end":"2026-02-28T01:32:23.119296Z","steps":["trace[617780722] 'range keys from in-memory index tree' (duration: 1.352017273s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.119025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.120368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-28T01:32:23.11936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:21.767036Z","time spent":"1.35231583s","remote":"127.0.0.1:55074","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-02-28T01:32:23.119374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:22.675548Z","time spent":"443.811968ms","remote":"127.0.0.1:55074","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-02-28T01:32:23.119405Z","caller":"traceutil/trace.go:171","msg":"trace[2033119111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:890; }","duration":"100.529368ms","start":"2026-02-28T01:32:23.018861Z","end":"2026-02-28T01:32:23.11939Z","steps":["trace[2033119111] 'range keys from in-memory index tree' (duration: 99.971484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.119678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192751046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:32:23.11981Z","caller":"traceutil/trace.go:171","msg":"trace[1590418320] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:890; }","duration":"1.19330645s","start":"2026-02-28T01:32:21.926477Z","end":"2026-02-28T01:32:23.119783Z","steps":["trace[1590418320] 'count revisions from in-memory index tree' (duration: 1.192646294s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.119878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:21.926454Z","time spent":"1.193406072s","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:32:23.722406Z","caller":"traceutil/trace.go:171","msg":"trace[340094792] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"589.200361ms","start":"2026-02-28T01:32:23.133185Z","end":"2026-02-28T01:32:23.722385Z","steps":["trace[340094792] 'process raft request' (duration: 589.060748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.72255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.133163Z","time spent":"589.302554ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:23.74122Z","caller":"traceutil/trace.go:171","msg":"trace[2085947185] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:929; }","duration":"335.969044ms","start":"2026-02-28T01:32:23.405216Z","end":"2026-02-28T01:32:23.741185Z","steps":["trace[2085947185] 'read index received' (duration: 317.081698ms)","trace[2085947185] 'applied index is now lower than readState.Index' (duration: 18.886016ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:23.741508Z","caller":"traceutil/trace.go:171","msg":"trace[1718919613] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"608.124479ms","start":"2026-02-28T01:32:23.133371Z","end":"2026-02-28T01:32:23.741495Z","steps":["trace[1718919613] 'process raft request' (duration: 607.620566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.741617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.133364Z","time spent":"608.1818ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:23.741783Z","caller":"traceutil/trace.go:171","msg":"trace[1788437191] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"608.373634ms","start":"2026-02-28T01:32:23.1334Z","end":"2026-02-28T01:32:23.741774Z","steps":["trace[1788437191] 'process raft request' (duration: 607.713198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.741839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.133384Z","time spent":"608.428395ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:23.74196Z","caller":"traceutil/trace.go:171","msg":"trace[1665088046] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"607.941544ms","start":"2026-02-28T01:32:23.13401Z","end":"2026-02-28T01:32:23.741952Z","steps":["trace[1665088046] 'process raft request' (duration: 607.136924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.742017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.133974Z","time spent":"608.017115ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:23.742214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.99816ms","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-02-28T01:32:23.742251Z","caller":"traceutil/trace.go:171","msg":"trace[1119017492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"337.087371ms","start":"2026-02-28T01:32:23.405154Z","end":"2026-02-28T01:32:23.742242Z","steps":["trace[1119017492] 'agreement among raft nodes before linearized reading' (duration: 336.966509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.74228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.405097Z","time spent":"337.177063ms","remote":"127.0.0.1:55074","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-02-28T01:32:23.742411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.588878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:32:23.742444Z","caller":"traceutil/trace.go:171","msg":"trace[750626175] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:894; }","duration":"319.625049ms","start":"2026-02-28T01:32:23.422811Z","end":"2026-02-28T01:32:23.742436Z","steps":["trace[750626175] 'agreement among raft nodes before linearized reading' (duration: 319.573508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:23.742475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:23.422733Z","time spent":"319.734282ms","remote":"127.0.0.1:55206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:32:35.996755Z","caller":"traceutil/trace.go:171","msg":"trace[813067256] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"158.552071ms","start":"2026-02-28T01:32:35.838145Z","end":"2026-02-28T01:32:35.996697Z","steps":["trace[813067256] 'process raft request' (duration: 158.336116ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:36.022072Z","caller":"traceutil/trace.go:171","msg":"trace[425743113] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"181.481029ms","start":"2026-02-28T01:32:35.840581Z","end":"2026-02-28T01:32:36.022062Z","steps":["trace[425743113] 'process raft request' (duration: 181.429618ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:36.022114Z","caller":"traceutil/trace.go:171","msg":"trace[1441537418] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"182.458412ms","start":"2026-02-28T01:32:35.839623Z","end":"2026-02-28T01:32:36.022081Z","steps":["trace[1441537418] 'process raft request' (duration: 182.327769ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:36.02242Z","caller":"traceutil/trace.go:171","msg":"trace[376602776] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"180.556948ms","start":"2026-02-28T01:32:35.841852Z","end":"2026-02-28T01:32:36.022409Z","steps":["trace[376602776] 'process raft request' (duration: 180.182049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:38.376838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.125774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T01:32:38.376875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.841203ms","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-02-28T01:32:38.376982Z","caller":"traceutil/trace.go:171","msg":"trace[183585228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"234.3492ms","start":"2026-02-28T01:32:38.142588Z","end":"2026-02-28T01:32:38.376938Z","steps":["trace[183585228] 'range keys from in-memory index tree' (duration: 233.999682ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:38.37699Z","caller":"traceutil/trace.go:171","msg":"trace[272173402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"350.994728ms","start":"2026-02-28T01:32:38.025965Z","end":"2026-02-28T01:32:38.37696Z","steps":["trace[272173402] 'range keys from in-memory index tree' (duration: 350.66628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:38.377047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:38.025962Z","time spent":"351.07431ms","remote":"127.0.0.1:55074","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-02-28T01:32:38.377034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.891445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:32:38.377126Z","caller":"traceutil/trace.go:171","msg":"trace[859798696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"374.020128ms","start":"2026-02-28T01:32:38.003081Z","end":"2026-02-28T01:32:38.377101Z","steps":["trace[859798696] 'range keys from in-memory index tree' (duration: 373.725901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:38.37716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:38.003064Z","time spent":"374.088569ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T01:32:38.377265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.748255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-28T01:32:38.377309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.213282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:32:38.377333Z","caller":"traceutil/trace.go:171","msg":"trace[1246833356] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:930; }","duration":"267.292153ms","start":"2026-02-28T01:32:38.110032Z","end":"2026-02-28T01:32:38.377324Z","steps":["trace[1246833356] 'count revisions from in-memory index tree' (duration: 267.100439ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:38.377335Z","caller":"traceutil/trace.go:171","msg":"trace[530953692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"351.837037ms","start":"2026-02-28T01:32:38.025478Z","end":"2026-02-28T01:32:38.377315Z","steps":["trace[530953692] 'range keys from in-memory index tree' (duration: 351.613522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:38.377368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:38.025473Z","time spent":"351.888928ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T01:32:38.377485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.959687ms","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-02-28T01:32:38.377569Z","caller":"traceutil/trace.go:171","msg":"trace[692836962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"351.08093ms","start":"2026-02-28T01:32:38.026468Z","end":"2026-02-28T01:32:38.377548Z","steps":["trace[692836962] 'range keys from in-memory index tree' (duration: 350.807073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:38.378387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:38.026463Z","time spent":"351.141531ms","remote":"127.0.0.1:55074","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-02-28T01:32:40.261784Z","caller":"traceutil/trace.go:171","msg":"trace[1394626589] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"120.081508ms","start":"2026-02-28T01:32:40.14168Z","end":"2026-02-28T01:32:40.261762Z","steps":["trace[1394626589] 'read index received' (duration: 119.863943ms)","trace[1394626589] 'applied index is now lower than readState.Index' (duration: 216.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:40.261872Z","caller":"traceutil/trace.go:171","msg":"trace[1769143851] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"191.32479ms","start":"2026-02-28T01:32:40.070504Z","end":"2026-02-28T01:32:40.261829Z","steps":["trace[1769143851] 'process raft request' (duration: 191.103635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:40.262028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.261662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:40.262204Z","caller":"traceutil/trace.go:171","msg":"trace[1623406323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"120.538479ms","start":"2026-02-28T01:32:40.141635Z","end":"2026-02-28T01:32:40.262174Z","steps":["trace[1623406323] 'agreement among raft nodes before linearized reading' (duration: 120.260872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:42.855242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.617412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:42.85548Z","caller":"traceutil/trace.go:171","msg":"trace[857100236] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"395.251366ms","start":"2026-02-28T01:32:42.460207Z","end":"2026-02-28T01:32:42.855458Z","steps":["trace[857100236] 'process raft request' (duration: 395.190795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:42.855565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:42.460189Z","time spent":"395.342698ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:42.856047Z","caller":"traceutil/trace.go:171","msg":"trace[2063554560] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"444.794059ms","start":"2026-02-28T01:32:42.41121Z","end":"2026-02-28T01:32:42.856004Z","steps":["trace[2063554560] 'process raft request' (duration: 314.307127ms)","trace[2063554560] 'compare' (duration: 129.421887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:42.856115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:42.411197Z","time spent":"444.888651ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:42.856254Z","caller":"traceutil/trace.go:171","msg":"trace[461932826] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"400.024218ms","start":"2026-02-28T01:32:42.456219Z","end":"2026-02-28T01:32:42.856243Z","steps":["trace[461932826] 'read index received' (duration: 269.365481ms)","trace[461932826] 'applied index is now lower than readState.Index' (duration: 130.653827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:42.85639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.246599ms","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-02-28T01:32:42.856422Z","caller":"traceutil/trace.go:171","msg":"trace[804519579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"445.289431ms","start":"2026-02-28T01:32:42.411123Z","end":"2026-02-28T01:32:42.856413Z","steps":["trace[804519579] 'agreement among raft nodes before linearized reading' (duration: 445.161388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:42.856455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:42.411118Z","time spent":"445.331341ms","remote":"127.0.0.1:55074","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-02-28T01:32:44.607123Z","caller":"traceutil/trace.go:171","msg":"trace[1103186333] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"141.416569ms","start":"2026-02-28T01:32:44.465679Z","end":"2026-02-28T01:32:44.607096Z","steps":["trace[1103186333] 'process raft request' (duration: 141.229504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.393588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.171678ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-28T01:32:46.394755Z","caller":"traceutil/trace.go:171","msg":"trace[109796607] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"481.068671ms","start":"2026-02-28T01:32:45.913659Z","end":"2026-02-28T01:32:46.394727Z","steps":["trace[109796607] 'read index received' (duration: 312.854303ms)","trace[109796607] 'applied index is now lower than readState.Index' (duration: 168.212858ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:46.394825Z","caller":"traceutil/trace.go:171","msg":"trace[81923264] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"775.06155ms","start":"2026-02-28T01:32:45.619727Z","end":"2026-02-28T01:32:46.394789Z","steps":["trace[81923264] 'process raft request' (duration: 774.057167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.395308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:45.61969Z","time spent":"775.545122ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:46.395062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.390078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:32:46.395425Z","caller":"traceutil/trace.go:171","msg":"trace[1113202793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"481.799797ms","start":"2026-02-28T01:32:45.913588Z","end":"2026-02-28T01:32:46.395388Z","steps":["trace[1113202793] 'agreement among raft nodes before linearized reading' (duration: 481.287165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.395574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:45.913551Z","time spent":"481.979632ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T01:32:46.395724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.466626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:46.395791Z","caller":"traceutil/trace.go:171","msg":"trace[1152337082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"255.585218ms","start":"2026-02-28T01:32:46.140189Z","end":"2026-02-28T01:32:46.395774Z","steps":["trace[1152337082] 'agreement among raft nodes before linearized reading' (duration: 255.460596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.395808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.397124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-28T01:32:46.395859Z","caller":"traceutil/trace.go:171","msg":"trace[1077816258] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:986; }","duration":"137.482826ms","start":"2026-02-28T01:32:46.258352Z","end":"2026-02-28T01:32:46.395835Z","steps":["trace[1077816258] 'agreement among raft nodes before linearized reading' (duration: 137.320462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.743059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.835785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:46.743203Z","caller":"traceutil/trace.go:171","msg":"trace[1709482521] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"341.506484ms","start":"2026-02-28T01:32:46.401672Z","end":"2026-02-28T01:32:46.743179Z","steps":["trace[1709482521] 'read index received' (duration: 82.749622ms)","trace[1709482521] 'applied index is now lower than readState.Index' (duration: 258.754162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:46.743344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.663708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:46.743408Z","caller":"traceutil/trace.go:171","msg":"trace[563091966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"341.77114ms","start":"2026-02-28T01:32:46.401621Z","end":"2026-02-28T01:32:46.743392Z","steps":["trace[563091966] 'agreement among raft nodes before linearized reading' (duration: 341.677208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:46.743445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:46.401601Z","time spent":"341.833252ms","remote":"127.0.0.1:54788","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-02-28T01:32:46.743493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.716652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-28T01:32:46.743547Z","caller":"traceutil/trace.go:171","msg":"trace[714263899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"131.832054ms","start":"2026-02-28T01:32:46.611703Z","end":"2026-02-28T01:32:46.743535Z","steps":["trace[714263899] 'agreement among raft nodes before linearized reading' (duration: 131.64078ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:46.743657Z","caller":"traceutil/trace.go:171","msg":"trace[692385805] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"341.775631ms","start":"2026-02-28T01:32:46.401614Z","end":"2026-02-28T01:32:46.74339Z","steps":["trace[692385805] 'process raft request' (duration: 183.481996ms)","trace[692385805] 'compare' (duration: 157.65997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:46.74396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:46.401601Z","time spent":"342.211161ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:47.053672Z","caller":"traceutil/trace.go:171","msg":"trace[994349857] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"181.52594ms","start":"2026-02-28T01:32:46.872088Z","end":"2026-02-28T01:32:47.053614Z","steps":["trace[994349857] 'read index received' (duration: 181.377237ms)","trace[994349857] 'applied index is now lower than readState.Index' (duration: 147.353µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:47.054071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.982309ms","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-02-28T01:32:47.054191Z","caller":"traceutil/trace.go:171","msg":"trace[1211402833] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:989; }","duration":"170.174973ms","start":"2026-02-28T01:32:46.883986Z","end":"2026-02-28T01:32:47.054161Z","steps":["trace[1211402833] 'agreement among raft nodes before linearized reading' (duration: 169.996459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:47.054324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.204396ms","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-02-28T01:32:47.054454Z","caller":"traceutil/trace.go:171","msg":"trace[362775349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"182.40696ms","start":"2026-02-28T01:32:46.872023Z","end":"2026-02-28T01:32:47.05443Z","steps":["trace[362775349] 'agreement among raft nodes before linearized reading' (duration: 181.848567ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:47.054614Z","caller":"traceutil/trace.go:171","msg":"trace[1594356442] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"305.9532ms","start":"2026-02-28T01:32:46.748645Z","end":"2026-02-28T01:32:47.054598Z","steps":["trace[1594356442] 'process raft request' (duration: 304.553087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:47.05483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.291573ms","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-02-28T01:32:47.054909Z","caller":"traceutil/trace.go:171","msg":"trace[1882923581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"178.388196ms","start":"2026-02-28T01:32:46.876507Z","end":"2026-02-28T01:32:47.054896Z","steps":["trace[1882923581] 'agreement among raft nodes before linearized reading' (duration: 177.614558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:47.054915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:46.74862Z","time spent":"306.072472ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:47.055063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.880982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:32:47.055191Z","caller":"traceutil/trace.go:171","msg":"trace[2079931737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"183.065106ms","start":"2026-02-28T01:32:46.872111Z","end":"2026-02-28T01:32:47.055176Z","steps":["trace[2079931737] 'agreement among raft nodes before linearized reading' (duration: 181.807016ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:47.237244Z","caller":"traceutil/trace.go:171","msg":"trace[1710894841] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"176.944533ms","start":"2026-02-28T01:32:47.060265Z","end":"2026-02-28T01:32:47.237209Z","steps":["trace[1710894841] 'process raft request' (duration: 86.119212ms)","trace[1710894841] 'compare' (duration: 90.565215ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:47.237274Z","caller":"traceutil/trace.go:171","msg":"trace[26719779] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"176.768999ms","start":"2026-02-28T01:32:47.060474Z","end":"2026-02-28T01:32:47.237243Z","steps":["trace[26719779] 'process raft request' (duration: 176.669597ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:47.237846Z","caller":"traceutil/trace.go:171","msg":"trace[78817971] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"176.438721ms","start":"2026-02-28T01:32:47.061166Z","end":"2026-02-28T01:32:47.237604Z","steps":["trace[78817971] 'process raft request' (duration: 176.057252ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:49.357996Z","caller":"traceutil/trace.go:171","msg":"trace[1766454015] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"431.884886ms","start":"2026-02-28T01:32:48.926085Z","end":"2026-02-28T01:32:49.357969Z","steps":["trace[1766454015] 'read index received' (duration: 431.667281ms)","trace[1766454015] 'applied index is now lower than readState.Index' (duration: 216.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:49.358094Z","caller":"traceutil/trace.go:171","msg":"trace[1037192646] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"604.501987ms","start":"2026-02-28T01:32:48.753538Z","end":"2026-02-28T01:32:49.35804Z","steps":["trace[1037192646] 'process raft request' (duration: 604.237411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:49.358285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.566114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-28T01:32:49.358311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.234268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:32:49.358334Z","caller":"traceutil/trace.go:171","msg":"trace[1071826082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"297.641096ms","start":"2026-02-28T01:32:49.060678Z","end":"2026-02-28T01:32:49.35832Z","steps":["trace[1071826082] 'agreement among raft nodes before linearized reading' (duration: 297.475902ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:49.35836Z","caller":"traceutil/trace.go:171","msg":"trace[793297774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"116.320869ms","start":"2026-02-28T01:32:49.242027Z","end":"2026-02-28T01:32:49.358348Z","steps":["trace[793297774] 'agreement among raft nodes before linearized reading' (duration: 116.187496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:49.358362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:48.75352Z","time spent":"604.692111ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:49.358486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.190786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:49.358519Z","caller":"traceutil/trace.go:171","msg":"trace[1509523353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"211.292418ms","start":"2026-02-28T01:32:49.147218Z","end":"2026-02-28T01:32:49.35851Z","steps":["trace[1509523353] 'agreement among raft nodes before linearized reading' (duration: 211.239427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:49.358483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.087184ms","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-02-28T01:32:49.358483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.375021ms","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-02-28T01:32:49.358603Z","caller":"traceutil/trace.go:171","msg":"trace[848567320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"116.267929ms","start":"2026-02-28T01:32:49.242304Z","end":"2026-02-28T01:32:49.358572Z","steps":["trace[848567320] 'agreement among raft nodes before linearized reading' (duration: 115.998822ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:49.358603Z","caller":"traceutil/trace.go:171","msg":"trace[1406273859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"116.571056ms","start":"2026-02-28T01:32:49.242015Z","end":"2026-02-28T01:32:49.358586Z","steps":["trace[1406273859] 'agreement among raft nodes before linearized reading' (duration: 116.249828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:49.359009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.92984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-28T01:32:49.359069Z","caller":"traceutil/trace.go:171","msg":"trace[1857567166] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:994; }","duration":"432.992432ms","start":"2026-02-28T01:32:48.926061Z","end":"2026-02-28T01:32:49.359053Z","steps":["trace[1857567166] 'agreement among raft nodes before linearized reading' (duration: 432.0545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:49.359106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:48.92596Z","time spent":"433.134596ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-28T01:32:50.037874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.013076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:50.038158Z","caller":"traceutil/trace.go:171","msg":"trace[586254747] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1045; }","duration":"208.029122ms","start":"2026-02-28T01:32:49.830107Z","end":"2026-02-28T01:32:50.038136Z","steps":["trace[586254747] 'read index received' (duration: 199.800459ms)","trace[586254747] 'applied index is now lower than readState.Index' (duration: 8.227123ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:50.038179Z","caller":"traceutil/trace.go:171","msg":"trace[2057921652] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"386.04321ms","start":"2026-02-28T01:32:49.652055Z","end":"2026-02-28T01:32:50.038098Z","steps":["trace[2057921652] 'process raft request' (duration: 173.690936ms)","trace[2057921652] 'compare' (duration: 211.824661ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:50.038496Z","caller":"traceutil/trace.go:171","msg":"trace[1593958678] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"351.313185ms","start":"2026-02-28T01:32:49.687169Z","end":"2026-02-28T01:32:50.038482Z","steps":["trace[1593958678] 'process raft request' (duration: 350.919965ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:50.038554Z","caller":"traceutil/trace.go:171","msg":"trace[1777599936] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"383.896449ms","start":"2026-02-28T01:32:49.65461Z","end":"2026-02-28T01:32:50.038506Z","steps":["trace[1777599936] 'process raft request' (duration: 383.409898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:50.03838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.269768ms","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-02-28T01:32:50.038748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:49.654578Z","time spent":"384.070654ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:50.038752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:49.687155Z","time spent":"351.388046ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T01:32:50.038747Z","caller":"traceutil/trace.go:171","msg":"trace[763921357] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:1004; }","duration":"208.653847ms","start":"2026-02-28T01:32:49.830052Z","end":"2026-02-28T01:32:50.038706Z","steps":["trace[763921357] 'agreement among raft nodes before linearized reading' (duration: 208.151265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:50.038605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:49.652041Z","time spent":"386.380047ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T01:32:50.272037Z","caller":"traceutil/trace.go:171","msg":"trace[1193825075] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"228.333928ms","start":"2026-02-28T01:32:50.043677Z","end":"2026-02-28T01:32:50.272011Z","steps":["trace[1193825075] 'process raft request' (duration: 228.211875ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:51.652227Z","caller":"traceutil/trace.go:171","msg":"trace[453720773] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"263.547052ms","start":"2026-02-28T01:32:51.388656Z","end":"2026-02-28T01:32:51.652203Z","steps":["trace[453720773] 'read index received' (duration: 263.42259ms)","trace[453720773] 'applied index is now lower than readState.Index' (duration: 123.132µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:51.652487Z","caller":"traceutil/trace.go:171","msg":"trace[739442610] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"284.012284ms","start":"2026-02-28T01:32:51.368449Z","end":"2026-02-28T01:32:51.652461Z","steps":["trace[739442610] 'process raft request' (duration: 283.582733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:51.652693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.012424ms","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-02-28T01:32:51.652805Z","caller":"traceutil/trace.go:171","msg":"trace[1257345245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"264.163647ms","start":"2026-02-28T01:32:51.388626Z","end":"2026-02-28T01:32:51.65279Z","steps":["trace[1257345245] 'agreement among raft nodes before linearized reading' (duration: 263.87335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:51.652885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.909935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-28T01:32:51.652911Z","caller":"traceutil/trace.go:171","msg":"trace[750454666] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1007; }","duration":"163.962237ms","start":"2026-02-28T01:32:51.488941Z","end":"2026-02-28T01:32:51.652903Z","steps":["trace[750454666] 'agreement among raft nodes before linearized reading' (duration: 163.911276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:51.652835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.975766ms","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-02-28T01:32:51.653048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.264099ms","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-02-28T01:32:51.653071Z","caller":"traceutil/trace.go:171","msg":"trace[1980611758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"183.339412ms","start":"2026-02-28T01:32:51.469725Z","end":"2026-02-28T01:32:51.653065Z","steps":["trace[1980611758] 'agreement among raft nodes before linearized reading' (duration: 183.28648ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:51.653042Z","caller":"traceutil/trace.go:171","msg":"trace[1787701278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"184.233992ms","start":"2026-02-28T01:32:51.468796Z","end":"2026-02-28T01:32:51.65303Z","steps":["trace[1787701278] 'agreement among raft nodes before linearized reading' (duration: 183.949555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:51.653172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.311104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-28T01:32:51.653193Z","caller":"traceutil/trace.go:171","msg":"trace[1920437916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"184.364496ms","start":"2026-02-28T01:32:51.468823Z","end":"2026-02-28T01:32:51.653187Z","steps":["trace[1920437916] 'agreement among raft nodes before linearized reading' (duration: 184.312564ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:52.340324Z","caller":"traceutil/trace.go:171","msg":"trace[1468870943] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"683.631615ms","start":"2026-02-28T01:32:51.656668Z","end":"2026-02-28T01:32:52.3403Z","steps":["trace[1468870943] 'process raft request' (duration: 683.43559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:52.340473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.656653Z","time spent":"683.740687ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:52.340655Z","caller":"traceutil/trace.go:171","msg":"trace[2068180270] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"683.535292ms","start":"2026-02-28T01:32:51.657095Z","end":"2026-02-28T01:32:52.340631Z","steps":["trace[2068180270] 'process raft request' (duration: 683.163674ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:32:52.340752Z","caller":"traceutil/trace.go:171","msg":"trace[536204751] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"683.198344ms","start":"2026-02-28T01:32:51.657514Z","end":"2026-02-28T01:32:52.340712Z","steps":["trace[536204751] 'process raft request' (duration: 682.969609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:52.340835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.657507Z","time spent":"683.276166ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:52.340837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.657088Z","time spent":"683.674515ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:52.340652Z","caller":"traceutil/trace.go:171","msg":"trace[1091816606] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"682.354064ms","start":"2026-02-28T01:32:51.658272Z","end":"2026-02-28T01:32:52.340626Z","steps":["trace[1091816606] 'process raft request' (duration: 682.307753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:52.341062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.658265Z","time spent":"682.750823ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:52.631827Z","caller":"traceutil/trace.go:171","msg":"trace[2067200897] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1053; }","duration":"771.773293ms","start":"2026-02-28T01:32:51.860033Z","end":"2026-02-28T01:32:52.631806Z","steps":["trace[2067200897] 'read index received' (duration: 480.075377ms)","trace[2067200897] 'applied index is now lower than readState.Index' (duration: 291.696446ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:52.63186Z","caller":"traceutil/trace.go:171","msg":"trace[2051944326] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"897.700888ms","start":"2026-02-28T01:32:51.734116Z","end":"2026-02-28T01:32:52.631817Z","steps":["trace[2051944326] 'process raft request' (duration: 875.582679ms)","trace[2051944326] 'compare' (duration: 21.843153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:52.631975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.335616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:32:52.632006Z","caller":"traceutil/trace.go:171","msg":"trace[145284974] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1013; }","duration":"233.440908ms","start":"2026-02-28T01:32:52.398555Z","end":"2026-02-28T01:32:52.631996Z","steps":["trace[145284974] 'agreement among raft nodes before linearized reading' (duration: 233.356866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:52.631991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.944057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-28T01:32:52.63204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.734088Z","time spent":"897.853851ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:52.632056Z","caller":"traceutil/trace.go:171","msg":"trace[60217477] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1013; }","duration":"772.060049ms","start":"2026-02-28T01:32:51.859974Z","end":"2026-02-28T01:32:52.632034Z","steps":["trace[60217477] 'agreement among raft nodes before linearized reading' (duration: 771.920176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:52.632085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:51.859942Z","time spent":"772.137391ms","remote":"127.0.0.1:55160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:32:54.015678Z","caller":"traceutil/trace.go:171","msg":"trace[2117923864] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"343.247495ms","start":"2026-02-28T01:32:53.672404Z","end":"2026-02-28T01:32:54.015652Z","steps":["trace[2117923864] 'read index received' (duration: 342.979179ms)","trace[2117923864] 'applied index is now lower than readState.Index' (duration: 267.266µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:32:54.015777Z","caller":"traceutil/trace.go:171","msg":"trace[1470935486] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"353.158458ms","start":"2026-02-28T01:32:53.662566Z","end":"2026-02-28T01:32:54.015724Z","steps":["trace[1470935486] 'process raft request' (duration: 352.935253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:54.016016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:53.662538Z","time spent":"353.361062ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:54.016122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.618354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-28T01:32:54.016199Z","caller":"traceutil/trace.go:171","msg":"trace[553177260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"343.811038ms","start":"2026-02-28T01:32:53.672373Z","end":"2026-02-28T01:32:54.016184Z","steps":["trace[553177260] 'agreement among raft nodes before linearized reading' (duration: 343.46383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:54.016266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:53.672363Z","time spent":"343.890181ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T01:32:54.413241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.332482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:32:54.413701Z","caller":"traceutil/trace.go:171","msg":"trace[1897600099] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"370.2927ms","start":"2026-02-28T01:32:54.043383Z","end":"2026-02-28T01:32:54.413675Z","steps":["trace[1897600099] 'read index received' (duration: 264.292463ms)","trace[1897600099] 'applied index is now lower than readState.Index' (duration: 105.997807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:54.413888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.501245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:54.41395Z","caller":"traceutil/trace.go:171","msg":"trace[1030503976] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1015; }","duration":"370.612807ms","start":"2026-02-28T01:32:54.04332Z","end":"2026-02-28T01:32:54.413933Z","steps":["trace[1030503976] 'agreement among raft nodes before linearized reading' (duration: 370.506425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:54.414025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.043296Z","time spent":"370.69822ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:32:54.413939Z","caller":"traceutil/trace.go:171","msg":"trace[436962477] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"392.828179ms","start":"2026-02-28T01:32:54.021022Z","end":"2026-02-28T01:32:54.41385Z","steps":["trace[436962477] 'process raft request' (duration: 286.76974ms)","trace[436962477] 'compare' (duration: 105.085097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:54.414356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.020993Z","time spent":"393.246399ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:32:54.414497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.402473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:32:54.414653Z","caller":"traceutil/trace.go:171","msg":"trace[979900963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"272.624949ms","start":"2026-02-28T01:32:54.141952Z","end":"2026-02-28T01:32:54.414577Z","steps":["trace[979900963] 'agreement among raft nodes before linearized reading' (duration: 272.426664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:54.969807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536859632216506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T01:32:55.116499Z","caller":"traceutil/trace.go:171","msg":"trace[1068605546] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"697.598961ms","start":"2026-02-28T01:32:54.418866Z","end":"2026-02-28T01:32:55.116465Z","steps":["trace[1068605546] 'process raft request' (duration: 697.369825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:55.116778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.418842Z","time spent":"697.760054ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:55.210359Z","caller":"traceutil/trace.go:171","msg":"trace[1193219596] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"791.119955ms","start":"2026-02-28T01:32:54.4192Z","end":"2026-02-28T01:32:55.21032Z","steps":["trace[1193219596] 'process raft request' (duration: 790.91464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:55.210686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.419185Z","time spent":"791.261678ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:55.234061Z","caller":"traceutil/trace.go:171","msg":"trace[1435687555] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"814.607486ms","start":"2026-02-28T01:32:54.419412Z","end":"2026-02-28T01:32:55.23402Z","steps":["trace[1435687555] 'process raft request' (duration: 790.856159ms)","trace[1435687555] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 23.527192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:55.234337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.419381Z","time spent":"814.795161ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:32:55.238318Z","caller":"traceutil/trace.go:171","msg":"trace[1530104176] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1060; }","duration":"769.134299ms","start":"2026-02-28T01:32:54.469149Z","end":"2026-02-28T01:32:55.238283Z","steps":["trace[1530104176] 'read index received' (duration: 647.176017ms)","trace[1530104176] 'applied index is now lower than readState.Index' (duration: 121.956382ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:32:55.238731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.57585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-28T01:32:55.23888Z","caller":"traceutil/trace.go:171","msg":"trace[1225499042] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1018; }","duration":"769.741414ms","start":"2026-02-28T01:32:54.469112Z","end":"2026-02-28T01:32:55.238853Z","steps":["trace[1225499042] 'agreement among raft nodes before linearized reading' (duration: 769.312594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:55.23893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:32:54.4691Z","time spent":"769.818755ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-28T01:32:55.239288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.768411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-28T01:32:55.239367Z","caller":"traceutil/trace.go:171","msg":"trace[1410996987] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1018; }","duration":"194.926724ms","start":"2026-02-28T01:32:55.044415Z","end":"2026-02-28T01:32:55.239341Z","steps":["trace[1410996987] 'agreement among raft nodes before linearized reading' (duration: 194.072294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:32:56.663199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.052808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hvmdv\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-02-28T01:32:56.663317Z","caller":"traceutil/trace.go:171","msg":"trace[889907631] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-hvmdv; range_end:; response_count:1; response_revision:1036; }","duration":"189.235752ms","start":"2026-02-28T01:32:56.474059Z","end":"2026-02-28T01:32:56.663295Z","steps":["trace[889907631] 'range keys from in-memory index tree' (duration: 188.795132ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:33:47.931672Z","caller":"traceutil/trace.go:171","msg":"trace[529995015] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"155.577651ms","start":"2026-02-28T01:33:47.776068Z","end":"2026-02-28T01:33:47.931646Z","steps":["trace[529995015] 'process raft request' (duration: 155.402947ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:26.5946Z","caller":"traceutil/trace.go:171","msg":"trace[1384528787] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"109.240364ms","start":"2026-02-28T01:34:26.485323Z","end":"2026-02-28T01:34:26.594564Z","steps":["trace[1384528787] 'process raft request' (duration: 109.05854ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:27.842735Z","caller":"traceutil/trace.go:171","msg":"trace[2063439700] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"289.799761ms","start":"2026-02-28T01:34:27.552909Z","end":"2026-02-28T01:34:27.842709Z","steps":["trace[2063439700] 'read index received' (duration: 289.609406ms)","trace[2063439700] 'applied index is now lower than readState.Index' (duration: 188.865µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:34:27.842852Z","caller":"traceutil/trace.go:171","msg":"trace[1376041201] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"315.375641ms","start":"2026-02-28T01:34:27.527434Z","end":"2026-02-28T01:34:27.842809Z","steps":["trace[1376041201] 'process raft request' (duration: 315.123785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:27.843067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.119439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-28T01:34:27.843146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.241915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:34:27.843173Z","caller":"traceutil/trace.go:171","msg":"trace[1253915110] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1409; }","duration":"290.276472ms","start":"2026-02-28T01:34:27.552869Z","end":"2026-02-28T01:34:27.843145Z","steps":["trace[1253915110] 'agreement among raft nodes before linearized reading' (duration: 289.969695ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:27.843232Z","caller":"traceutil/trace.go:171","msg":"trace[690729279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1409; }","duration":"193.357478ms","start":"2026-02-28T01:34:27.649852Z","end":"2026-02-28T01:34:27.843209Z","steps":["trace[690729279] 'agreement among raft nodes before linearized reading' (duration: 193.124242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:27.843253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.429988ms","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-02-28T01:34:27.843335Z","caller":"traceutil/trace.go:171","msg":"trace[2069723262] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1409; }","duration":"256.554621ms","start":"2026-02-28T01:34:27.586755Z","end":"2026-02-28T01:34:27.843309Z","steps":["trace[2069723262] 'agreement among raft nodes before linearized reading' (duration: 256.263494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:27.843614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.257821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-28T01:34:27.843624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:34:27.527417Z","time spent":"315.547516ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:34:27.84366Z","caller":"traceutil/trace.go:171","msg":"trace[1574830137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1409; }","duration":"284.379794ms","start":"2026-02-28T01:34:27.559265Z","end":"2026-02-28T01:34:27.843645Z","steps":["trace[1574830137] 'agreement among raft nodes before linearized reading' (duration: 283.646227ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:51.782722Z","caller":"traceutil/trace.go:171","msg":"trace[2076996838] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"129.515489ms","start":"2026-02-28T01:34:51.653176Z","end":"2026-02-28T01:34:51.782692Z","steps":["trace[2076996838] 'process raft request' (duration: 129.337415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:53.642925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536859632217754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:34:54.144039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536859632217754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:34:54.207673Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302490616s","expected-duration":"1s"} {"level":"info","ts":"2026-02-28T01:34:54.208189Z","caller":"traceutil/trace.go:171","msg":"trace[1461959061] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"1.065909023s","start":"2026-02-28T01:34:53.142245Z","end":"2026-02-28T01:34:54.208154Z","steps":["trace[1461959061] 'read index received' (duration: 1.065619296s)","trace[1461959061] 'applied index is now lower than readState.Index' (duration: 288.077µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:34:54.208239Z","caller":"traceutil/trace.go:171","msg":"trace[1158463082] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"1.303123361s","start":"2026-02-28T01:34:52.905084Z","end":"2026-02-28T01:34:54.208207Z","steps":["trace[1158463082] 'process raft request' (duration: 1.302823923s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.208427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.760188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T01:34:54.208428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:34:52.905068Z","time spent":"1.303229603s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:34:54.208454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066169489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:34:54.208591Z","caller":"traceutil/trace.go:171","msg":"trace[1780965023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1515; }","duration":"1.066391115s","start":"2026-02-28T01:34:53.142177Z","end":"2026-02-28T01:34:54.208568Z","steps":["trace[1780965023] 'agreement among raft nodes before linearized reading' (duration: 1.066154299s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.208598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.910192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:34:54.208632Z","caller":"traceutil/trace.go:171","msg":"trace[645427489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1515; }","duration":"234.993944ms","start":"2026-02-28T01:34:53.973629Z","end":"2026-02-28T01:34:54.208623Z","steps":["trace[645427489] 'agreement among raft nodes before linearized reading' (duration: 234.864311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.208638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:34:53.142148Z","time spent":"1.066478826s","remote":"127.0.0.1:54788","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-02-28T01:34:54.208474Z","caller":"traceutil/trace.go:171","msg":"trace[834495205] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1515; }","duration":"646.88854ms","start":"2026-02-28T01:34:53.56157Z","end":"2026-02-28T01:34:54.208458Z","steps":["trace[834495205] 'agreement among raft nodes before linearized reading' (duration: 646.773978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.208801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.694591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-28T01:34:54.208933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.303142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T01:34:54.208932Z","caller":"traceutil/trace.go:171","msg":"trace[1698087686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1515; }","duration":"232.920716ms","start":"2026-02-28T01:34:53.975986Z","end":"2026-02-28T01:34:54.208906Z","steps":["trace[1698087686] 'agreement among raft nodes before linearized reading' (duration: 232.516006ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:54.208982Z","caller":"traceutil/trace.go:171","msg":"trace[351265848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1515; }","duration":"235.402674ms","start":"2026-02-28T01:34:53.973567Z","end":"2026-02-28T01:34:54.20897Z","steps":["trace[351265848] 'agreement among raft nodes before linearized reading' (duration: 235.287262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.208997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.09977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-28T01:34:54.209061Z","caller":"traceutil/trace.go:171","msg":"trace[1723318334] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1515; }","duration":"227.191332ms","start":"2026-02-28T01:34:53.981852Z","end":"2026-02-28T01:34:54.209043Z","steps":["trace[1723318334] 'agreement among raft nodes before linearized reading' (duration: 226.758492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.209129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.221574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:34:54.209163Z","caller":"traceutil/trace.go:171","msg":"trace[1729938105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1515; }","duration":"236.254495ms","start":"2026-02-28T01:34:53.9729Z","end":"2026-02-28T01:34:54.209154Z","steps":["trace[1729938105] 'agreement among raft nodes before linearized reading' (duration: 236.170113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:54.209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:34:53.561542Z","time spent":"647.335501ms","remote":"127.0.0.1:53778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:34:54.209614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.660493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-28T01:34:54.209807Z","caller":"traceutil/trace.go:171","msg":"trace[1395458306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1515; }","duration":"227.889268ms","start":"2026-02-28T01:34:53.981896Z","end":"2026-02-28T01:34:54.209785Z","steps":["trace[1395458306] 'agreement among raft nodes before linearized reading' (duration: 226.585538ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:00.777258Z","caller":"traceutil/trace.go:171","msg":"trace[678842609] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"194.31118ms","start":"2026-02-28T01:35:00.582903Z","end":"2026-02-28T01:35:00.777214Z","steps":["trace[678842609] 'process raft request' (duration: 194.051364ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:00.77804Z","caller":"traceutil/trace.go:171","msg":"trace[448549499] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"193.713326ms","start":"2026-02-28T01:35:00.584297Z","end":"2026-02-28T01:35:00.778011Z","steps":["trace[448549499] 'process raft request' (duration: 193.609503ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:36:18.927251Z","caller":"traceutil/trace.go:171","msg":"trace[931719222] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1978; }","duration":"152.99623ms","start":"2026-02-28T01:36:18.774217Z","end":"2026-02-28T01:36:18.927213Z","steps":["trace[931719222] 'read index received' (duration: 86.547356ms)","trace[931719222] 'applied index is now lower than readState.Index' (duration: 66.447374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:36:18.927611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.374128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T01:36:18.927669Z","caller":"traceutil/trace.go:171","msg":"trace[963833979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1893; }","duration":"153.488141ms","start":"2026-02-28T01:36:18.774171Z","end":"2026-02-28T01:36:18.92766Z","steps":["trace[963833979] 'agreement among raft nodes before linearized reading' (duration: 153.262526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:36:18.927746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.318395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-28T01:36:18.927837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.399938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-28T01:36:18.927878Z","caller":"traceutil/trace.go:171","msg":"trace[577843742] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1893; }","duration":"107.467409ms","start":"2026-02-28T01:36:18.820368Z","end":"2026-02-28T01:36:18.927835Z","steps":["trace[577843742] 'agreement among raft nodes before linearized reading' (duration: 107.188312ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:36:18.927902Z","caller":"traceutil/trace.go:171","msg":"trace[1554233854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"113.49084ms","start":"2026-02-28T01:36:18.814402Z","end":"2026-02-28T01:36:18.927893Z","steps":["trace[1554233854] 'agreement among raft nodes before linearized reading' (duration: 113.357287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:36:18.927987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.342181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-28T01:36:18.927354Z","caller":"traceutil/trace.go:171","msg":"trace[1673448499] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"163.978358ms","start":"2026-02-28T01:36:18.763339Z","end":"2026-02-28T01:36:18.927317Z","steps":["trace[1673448499] 'process raft request' (duration: 163.687501ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:36:18.928088Z","caller":"traceutil/trace.go:171","msg":"trace[1371708463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1893; }","duration":"114.564305ms","start":"2026-02-28T01:36:18.813507Z","end":"2026-02-28T01:36:18.928071Z","steps":["trace[1371708463] 'agreement among raft nodes before linearized reading' (duration: 114.044003ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:36:19.040753Z","caller":"traceutil/trace.go:171","msg":"trace[630729710] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"101.351235ms","start":"2026-02-28T01:36:18.93934Z","end":"2026-02-28T01:36:19.040691Z","steps":["trace[630729710] 'process raft request' (duration: 37.626485ms)","trace[630729710] 'compare' (duration: 63.352841ms)"],"step_count":2}