{"level":"warn","ts":"2026-03-20T05:31:14.865833Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:31:14.865972Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-20T05:31:14.866132Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:31:14.866154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-03-20T05:31:14.866181Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:31:14.867492Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-03-20T05:31:14.867647Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-20T05:31:14.871967Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.81679ms"} {"level":"info","ts":"2026-03-20T05:31:14.880235Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-03-20T05:31:14.880437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:31:14.880529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:31:14.880609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:31:14.880628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:31:14.880785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-03-20T05:31:14.887004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:31:14.888347Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:31:14.889673Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-20T05:31:14.891865Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:31:14.892199Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:31:14.892326Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:31:14.892456Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:31:14.892469Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:31:14.89893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-03-20T05:31:14.899123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-03-20T05:31:14.900015Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:31:14.900144Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-03-20T05:31:14.900154Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-03-20T05:31:14.900387Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:31:14.90042Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:31:15.58163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:31:15.581737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:31:15.581801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-03-20T05:31:15.581834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:31:15.581852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-03-20T05:31:15.581879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:31:15.5819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-03-20T05:31:15.804922Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:16.143466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:16.143583Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:31:16.143624Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:16.143625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:31:16.143677Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:16.143683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:31:16.143984Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:31:16.144069Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:31:16.14754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T05:31:16.147853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-03-20T05:31:16.311289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.278034ms","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-03-20T05:31:16.31137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.280585ms","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-03-20T05:31:16.311477Z","caller":"traceutil/trace.go:171","msg":"trace[1369631407] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"118.53213ms","start":"2026-03-20T05:31:16.192916Z","end":"2026-03-20T05:31:16.311448Z","steps":["trace[1369631407] 'range keys from in-memory index tree' (duration: 118.155461ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:16.311823Z","caller":"traceutil/trace.go:171","msg":"trace[305554090] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"118.700544ms","start":"2026-03-20T05:31:16.193037Z","end":"2026-03-20T05:31:16.311738Z","steps":["trace[305554090] 'count revisions from in-memory index tree' (duration: 118.222343ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:45.887488Z","caller":"traceutil/trace.go:171","msg":"trace[2131396411] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"135.366979ms","start":"2026-03-20T05:31:45.752068Z","end":"2026-03-20T05:31:45.887435Z","steps":["trace[2131396411] 'process raft request' (duration: 115.883077ms)","trace[2131396411] 'compare' (duration: 19.06439ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:46.085318Z","caller":"traceutil/trace.go:171","msg":"trace[370460726] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"112.625342ms","start":"2026-03-20T05:31:45.972556Z","end":"2026-03-20T05:31:46.085181Z","steps":["trace[370460726] 'process raft request' (duration: 58.387395ms)","trace[370460726] 'compare' (duration: 53.856598ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:46.232564Z","caller":"traceutil/trace.go:171","msg":"trace[285368438] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"140.881861ms","start":"2026-03-20T05:31:46.091654Z","end":"2026-03-20T05:31:46.232536Z","steps":["trace[285368438] 'process raft request' (duration: 140.830919ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:46.232672Z","caller":"traceutil/trace.go:171","msg":"trace[463429074] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"141.470406ms","start":"2026-03-20T05:31:46.091147Z","end":"2026-03-20T05:31:46.232617Z","steps":["trace[463429074] 'process raft request' (duration: 121.416218ms)","trace[463429074] 'compare' (duration: 19.76776ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:47.262246Z","caller":"traceutil/trace.go:171","msg":"trace[77891245] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"765.41217ms","start":"2026-03-20T05:31:46.496796Z","end":"2026-03-20T05:31:47.262208Z","steps":["trace[77891245] 'process raft request' (duration: 765.074932ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:47.262304Z","caller":"traceutil/trace.go:171","msg":"trace[787453133] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"765.236307ms","start":"2026-03-20T05:31:46.497044Z","end":"2026-03-20T05:31:47.26228Z","steps":["trace[787453133] 'process raft request' (duration: 765.034521ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:47.262751Z","caller":"traceutil/trace.go:171","msg":"trace[229139446] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"765.337209ms","start":"2026-03-20T05:31:46.497351Z","end":"2026-03-20T05:31:47.262689Z","steps":["trace[229139446] 'process raft request' (duration: 765.057472ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:47.262973Z","caller":"traceutil/trace.go:171","msg":"trace[1237872940] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:515; }","duration":"763.886861ms","start":"2026-03-20T05:31:46.499057Z","end":"2026-03-20T05:31:47.262944Z","steps":["trace[1237872940] 'read index received' (duration: 27.497409ms)","trace[1237872940] 'applied index is now lower than readState.Index' (duration: 736.388492ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:47.263099Z","caller":"traceutil/trace.go:171","msg":"trace[1401592879] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"758.547395ms","start":"2026-03-20T05:31:46.50454Z","end":"2026-03-20T05:31:47.263087Z","steps":["trace[1401592879] 'process raft request' (duration: 758.040411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.263148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.497327Z","time spent":"765.540473ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:31:47.263413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.348984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"warn","ts":"2026-03-20T05:31:47.263421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.504507Z","time spent":"758.617877ms","remote":"127.0.0.1:41558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:31:47.263463Z","caller":"traceutil/trace.go:171","msg":"trace[282058354] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:501; }","duration":"764.429076ms","start":"2026-03-20T05:31:46.499025Z","end":"2026-03-20T05:31:47.263454Z","steps":["trace[282058354] 'agreement among raft nodes before linearized reading' (duration: 764.344874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.26348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.499016Z","time spent":"764.459956ms","remote":"127.0.0.1:41924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4357,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-03-20T05:31:47.262729Z","caller":"traceutil/trace.go:171","msg":"trace[1952511944] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"765.378209ms","start":"2026-03-20T05:31:46.49721Z","end":"2026-03-20T05:31:47.262588Z","steps":["trace[1952511944] 'process raft request' (duration: 764.932928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.263577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.497037Z","time spent":"765.371651ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:31:47.263626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.497204Z","time spent":"766.359504ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:31:47.263725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.496765Z","time spent":"765.610676ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:31:47.325509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.201464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:31:47.325558Z","caller":"traceutil/trace.go:171","msg":"trace[542821836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"371.335417ms","start":"2026-03-20T05:31:46.954213Z","end":"2026-03-20T05:31:47.325548Z","steps":["trace[542821836] 'agreement among raft nodes before linearized reading' (duration: 371.191833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.325597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.954176Z","time spent":"371.41162ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:31:47.325575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.19695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwdq9\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-03-20T05:31:47.325658Z","caller":"traceutil/trace.go:171","msg":"trace[1025020328] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwdq9; range_end:; response_count:1; response_revision:503; }","duration":"790.354714ms","start":"2026-03-20T05:31:46.535281Z","end":"2026-03-20T05:31:47.325636Z","steps":["trace[1025020328] 'agreement among raft nodes before linearized reading' (duration: 790.114498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.325703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.535243Z","time spent":"790.454075ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15098,"request content":"key:\"/registry/pods/kube-system/cilium-rwdq9\" "} {"level":"warn","ts":"2026-03-20T05:31:47.325756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.794243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-20T05:31:47.325832Z","caller":"traceutil/trace.go:171","msg":"trace[807419542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:503; }","duration":"514.923266ms","start":"2026-03-20T05:31:46.810893Z","end":"2026-03-20T05:31:47.325816Z","steps":["trace[807419542] 'agreement among raft nodes before linearized reading' (duration: 514.66365ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:47.325333Z","caller":"traceutil/trace.go:171","msg":"trace[615298047] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"792.505759ms","start":"2026-03-20T05:31:46.532798Z","end":"2026-03-20T05:31:47.325304Z","steps":["trace[615298047] 'process raft request' (duration: 783.762453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:47.32587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.810873Z","time spent":"514.989219ms","remote":"127.0.0.1:41668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":542,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-20T05:31:47.325966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:46.532768Z","time spent":"793.117144ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:31:56.463941Z","caller":"traceutil/trace.go:171","msg":"trace[353308228] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"360.58305ms","start":"2026-03-20T05:31:56.103335Z","end":"2026-03-20T05:31:56.463918Z","steps":["trace[353308228] 'read index received' (duration: 360.221461ms)","trace[353308228] 'applied index is now lower than readState.Index' (duration: 360.739µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:56.464073Z","caller":"traceutil/trace.go:171","msg":"trace[1097118648] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"507.738781ms","start":"2026-03-20T05:31:55.956299Z","end":"2026-03-20T05:31:56.464037Z","steps":["trace[1097118648] 'process raft request' (duration: 507.307561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:56.464271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:55.956279Z","time spent":"507.883955ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:31:56.464414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.521827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwdq9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-20T05:31:56.464522Z","caller":"traceutil/trace.go:171","msg":"trace[729907520] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwdq9; range_end:; response_count:1; response_revision:535; }","duration":"207.66094ms","start":"2026-03-20T05:31:56.256793Z","end":"2026-03-20T05:31:56.464454Z","steps":["trace[729907520] 'agreement among raft nodes before linearized reading' (duration: 207.474015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:56.464886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.209699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T05:31:56.465056Z","caller":"traceutil/trace.go:171","msg":"trace[1048622668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"280.440134ms","start":"2026-03-20T05:31:56.184547Z","end":"2026-03-20T05:31:56.464987Z","steps":["trace[1048622668] 'agreement among raft nodes before linearized reading' (duration: 279.717716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:56.464939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.610526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:31:56.465428Z","caller":"traceutil/trace.go:171","msg":"trace[2057220487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"362.147881ms","start":"2026-03-20T05:31:56.103264Z","end":"2026-03-20T05:31:56.465412Z","steps":["trace[2057220487] 'agreement among raft nodes before linearized reading' (duration: 360.789956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:56.465769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:56.103236Z","time spent":"362.224312ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:31:56.670604Z","caller":"traceutil/trace.go:171","msg":"trace[1993332374] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"200.719742ms","start":"2026-03-20T05:31:56.469855Z","end":"2026-03-20T05:31:56.670575Z","steps":["trace[1993332374] 'process raft request' (duration: 173.585712ms)","trace[1993332374] 'compare' (duration: 26.919885ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:56.684394Z","caller":"traceutil/trace.go:171","msg":"trace[651095952] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"169.800906ms","start":"2026-03-20T05:31:56.514575Z","end":"2026-03-20T05:31:56.684376Z","steps":["trace[651095952] 'read index received' (duration: 128.807779ms)","trace[651095952] 'applied index is now lower than readState.Index' (duration: 40.992337ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:31:56.684542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.961309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T05:31:56.684568Z","caller":"traceutil/trace.go:171","msg":"trace[1253925585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"170.064382ms","start":"2026-03-20T05:31:56.514497Z","end":"2026-03-20T05:31:56.684561Z","steps":["trace[1253925585] 'agreement among raft nodes before linearized reading' (duration: 169.98475ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:56.684645Z","caller":"traceutil/trace.go:171","msg":"trace[511286483] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"204.048367ms","start":"2026-03-20T05:31:56.480573Z","end":"2026-03-20T05:31:56.684621Z","steps":["trace[511286483] 'process raft request' (duration: 203.655937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:58.314023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35959293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:31:58.314109Z","caller":"traceutil/trace.go:171","msg":"trace[451501394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"1.359723033s","start":"2026-03-20T05:31:56.954368Z","end":"2026-03-20T05:31:58.314091Z","steps":["trace[451501394] 'range keys from in-memory index tree' (duration: 1.359509068s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:58.314146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:56.954349Z","time spent":"1.359789336s","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:31:58.31444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.096404ms","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-03-20T05:31:58.314527Z","caller":"traceutil/trace.go:171","msg":"trace[1020891724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"446.220797ms","start":"2026-03-20T05:31:57.868287Z","end":"2026-03-20T05:31:58.314508Z","steps":["trace[1020891724] 'range keys from in-memory index tree' (duration: 445.725815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:58.314629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:57.868271Z","time spent":"446.291998ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T05:31:58.507629Z","caller":"traceutil/trace.go:171","msg":"trace[1056520750] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"185.473148ms","start":"2026-03-20T05:31:58.322101Z","end":"2026-03-20T05:31:58.507574Z","steps":["trace[1056520750] 'read index received' (duration: 185.264903ms)","trace[1056520750] 'applied index is now lower than readState.Index' (duration: 207.275µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:58.507641Z","caller":"traceutil/trace.go:171","msg":"trace[303047421] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"188.188858ms","start":"2026-03-20T05:31:58.319422Z","end":"2026-03-20T05:31:58.507611Z","steps":["trace[303047421] 'process raft request' (duration: 188.011754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:58.50788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.763356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:31:58.507969Z","caller":"traceutil/trace.go:171","msg":"trace[1685052690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"185.942081ms","start":"2026-03-20T05:31:58.322008Z","end":"2026-03-20T05:31:58.50795Z","steps":["trace[1685052690] 'agreement among raft nodes before linearized reading' (duration: 185.718055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:59.181569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257185707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:31:59.624584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112895744s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:31:59.651633Z","caller":"traceutil/trace.go:171","msg":"trace[1385780978] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.140021492s","start":"2026-03-20T05:31:58.51158Z","end":"2026-03-20T05:31:59.651601Z","steps":["trace[1385780978] 'process raft request' (duration: 1.113239162s)","trace[1385780978] 'compare' (duration: 26.282857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:31:59.651792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.511556Z","time spent":"1.140160706s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:31:59.658614Z","caller":"traceutil/trace.go:171","msg":"trace[156194372] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:560; }","duration":"978.08144ms","start":"2026-03-20T05:31:58.680512Z","end":"2026-03-20T05:31:59.658594Z","steps":["trace[156194372] 'read index received' (duration: 944.29967ms)","trace[156194372] 'applied index is now lower than readState.Index' (duration: 33.78105ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:31:59.658721Z","caller":"traceutil/trace.go:171","msg":"trace[1946510664] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"920.842825ms","start":"2026-03-20T05:31:58.737857Z","end":"2026-03-20T05:31:59.6587Z","steps":["trace[1946510664] 'process raft request' (duration: 920.59955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:59.658841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.313184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-20T05:31:59.658832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.737829Z","time spent":"920.953497ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:31:59.658873Z","caller":"traceutil/trace.go:171","msg":"trace[37866936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"931.396967ms","start":"2026-03-20T05:31:58.727466Z","end":"2026-03-20T05:31:59.658863Z","steps":["trace[37866936] 'agreement among raft nodes before linearized reading' (duration: 931.258583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:59.658912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.727445Z","time spent":"931.460799ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:31:59.658959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.408539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T05:31:59.659035Z","caller":"traceutil/trace.go:171","msg":"trace[706475357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"978.538372ms","start":"2026-03-20T05:31:58.680471Z","end":"2026-03-20T05:31:59.65901Z","steps":["trace[706475357] 'agreement among raft nodes before linearized reading' (duration: 978.251774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:59.659103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:58.680454Z","time spent":"978.636364ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:31:59.659005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.403331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-20T05:31:59.65917Z","caller":"traceutil/trace.go:171","msg":"trace[2081716923] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:541; }","duration":"373.599016ms","start":"2026-03-20T05:31:59.28555Z","end":"2026-03-20T05:31:59.659149Z","steps":["trace[2081716923] 'agreement among raft nodes before linearized reading' (duration: 373.374131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:59.65922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:59.285536Z","time spent":"373.673148ms","remote":"127.0.0.1:41512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-03-20T05:32:00.080489Z","caller":"traceutil/trace.go:171","msg":"trace[687152392] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"416.186714ms","start":"2026-03-20T05:31:59.664282Z","end":"2026-03-20T05:32:00.080469Z","steps":["trace[687152392] 'process raft request' (duration: 416.02876ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:00.080477Z","caller":"traceutil/trace.go:171","msg":"trace[1320713032] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"417.290181ms","start":"2026-03-20T05:31:59.663152Z","end":"2026-03-20T05:32:00.080443Z","steps":["trace[1320713032] 'process raft request' (duration: 344.31136ms)","trace[1320713032] 'compare' (duration: 72.731934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:00.080631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:59.664259Z","time spent":"416.293236ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:00.080663Z","caller":"traceutil/trace.go:171","msg":"trace[728746024] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:563; }","duration":"128.946862ms","start":"2026-03-20T05:31:59.951703Z","end":"2026-03-20T05:32:00.08065Z","steps":["trace[728746024] 'read index received' (duration: 55.775458ms)","trace[728746024] 'applied index is now lower than readState.Index' (duration: 73.170454ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:00.080662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:59.663132Z","time spent":"417.433665ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:00.080742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.042674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:00.08078Z","caller":"traceutil/trace.go:171","msg":"trace[495541504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"129.120556ms","start":"2026-03-20T05:31:59.951652Z","end":"2026-03-20T05:32:00.080772Z","steps":["trace[495541504] 'agreement among raft nodes before linearized reading' (duration: 129.040844ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:00.080809Z","caller":"traceutil/trace.go:171","msg":"trace[1618290210] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"413.250947ms","start":"2026-03-20T05:31:59.667548Z","end":"2026-03-20T05:32:00.080799Z","steps":["trace[1618290210] 'process raft request' (duration: 412.880138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:00.080874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:59.667533Z","time spent":"413.314199ms","remote":"127.0.0.1:41512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:01.372847Z","caller":"traceutil/trace.go:171","msg":"trace[322864514] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"106.786622ms","start":"2026-03-20T05:32:01.266005Z","end":"2026-03-20T05:32:01.372791Z","steps":["trace[322864514] 'process raft request' (duration: 106.588256ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:01.524446Z","caller":"traceutil/trace.go:171","msg":"trace[1017814149] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"139.414952ms","start":"2026-03-20T05:32:01.385006Z","end":"2026-03-20T05:32:01.524421Z","steps":["trace[1017814149] 'process raft request' (duration: 139.258288ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:02.917279Z","caller":"traceutil/trace.go:171","msg":"trace[233587816] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"358.864156ms","start":"2026-03-20T05:32:02.558378Z","end":"2026-03-20T05:32:02.917242Z","steps":["trace[233587816] 'process raft request' (duration: 358.730843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:02.917435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:02.558352Z","time spent":"359.00391ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:11.809572Z","caller":"traceutil/trace.go:171","msg":"trace[346018287] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"100.261083ms","start":"2026-03-20T05:32:11.709286Z","end":"2026-03-20T05:32:11.809547Z","steps":["trace[346018287] 'process raft request' (duration: 76.246935ms)","trace[346018287] 'compare' (duration: 23.908605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:17.552488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.160851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-20T05:32:17.55264Z","caller":"traceutil/trace.go:171","msg":"trace[1716207582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"296.400728ms","start":"2026-03-20T05:32:17.256216Z","end":"2026-03-20T05:32:17.552617Z","steps":["trace[1716207582] 'range keys from in-memory index tree' (duration: 295.942646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.186124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257186062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:32:18.592168Z","caller":"traceutil/trace.go:171","msg":"trace[1065548197] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"906.485185ms","start":"2026-03-20T05:32:17.685641Z","end":"2026-03-20T05:32:18.592127Z","steps":["trace[1065548197] 'read index received' (duration: 906.358812ms)","trace[1065548197] 'applied index is now lower than readState.Index' (duration: 125.273µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:18.592219Z","caller":"traceutil/trace.go:171","msg":"trace[2041793437] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"983.152181ms","start":"2026-03-20T05:32:17.609015Z","end":"2026-03-20T05:32:18.592167Z","steps":["trace[2041793437] 'process raft request' (duration: 982.942686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.592374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.704041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:32:18.592408Z","caller":"traceutil/trace.go:171","msg":"trace[850036973] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:729; }","duration":"906.810433ms","start":"2026-03-20T05:32:17.685585Z","end":"2026-03-20T05:32:18.592395Z","steps":["trace[850036973] 'agreement among raft nodes before linearized reading' (duration: 906.704951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.592436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:17.685562Z","time spent":"906.867685ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:32:18.592775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:18.5928Z","caller":"traceutil/trace.go:171","msg":"trace[889781716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"640.529523ms","start":"2026-03-20T05:32:17.952262Z","end":"2026-03-20T05:32:18.592791Z","steps":["trace[889781716] 'agreement among raft nodes before linearized reading' (duration: 640.491952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.592823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:17.952241Z","time spent":"640.575134ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:32:18.592944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.976997ms","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-03-20T05:32:18.593031Z","caller":"traceutil/trace.go:171","msg":"trace[1355056479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"397.122082ms","start":"2026-03-20T05:32:18.195888Z","end":"2026-03-20T05:32:18.593011Z","steps":["trace[1355056479] 'agreement among raft nodes before linearized reading' (duration: 396.847344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.593078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:18.195867Z","time spent":"397.204315ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:32:18.592401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:17.608986Z","time spent":"983.317135ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:32:18.593249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.894946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T05:32:18.593363Z","caller":"traceutil/trace.go:171","msg":"trace[2009509656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"331.07865ms","start":"2026-03-20T05:32:18.262255Z","end":"2026-03-20T05:32:18.593334Z","steps":["trace[2009509656] 'agreement among raft nodes before linearized reading' (duration: 330.752542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.59342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:18.262229Z","time spent":"331.180603ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:32:18.593295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.654177ms","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-03-20T05:32:18.594294Z","caller":"traceutil/trace.go:171","msg":"trace[957569029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"758.892682ms","start":"2026-03-20T05:32:17.834568Z","end":"2026-03-20T05:32:18.593461Z","steps":["trace[957569029] 'agreement among raft nodes before linearized reading' (duration: 758.04459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:18.594353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:17.834539Z","time spent":"759.801546ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:32:18.873128Z","caller":"traceutil/trace.go:171","msg":"trace[1019551300] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"274.283567ms","start":"2026-03-20T05:32:18.598813Z","end":"2026-03-20T05:32:18.873097Z","steps":["trace[1019551300] 'process raft request' (duration: 274.222146ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:18.873217Z","caller":"traceutil/trace.go:171","msg":"trace[1409029164] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"275.95256ms","start":"2026-03-20T05:32:18.597206Z","end":"2026-03-20T05:32:18.873158Z","steps":["trace[1409029164] 'process raft request' (duration: 271.571047ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:18.873426Z","caller":"traceutil/trace.go:171","msg":"trace[279288019] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"275.683643ms","start":"2026-03-20T05:32:18.597732Z","end":"2026-03-20T05:32:18.873416Z","steps":["trace[279288019] 'process raft request' (duration: 275.245773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:21.451977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257186092,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:32:21.471774Z","caller":"traceutil/trace.go:171","msg":"trace[1769587171] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"588.77632ms","start":"2026-03-20T05:32:20.882962Z","end":"2026-03-20T05:32:21.471738Z","steps":["trace[1769587171] 'process raft request' (duration: 588.555274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:21.471978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:20.882933Z","time spent":"588.934163ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:21.695149Z","caller":"traceutil/trace.go:171","msg":"trace[26255207] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"812.101743ms","start":"2026-03-20T05:32:20.88302Z","end":"2026-03-20T05:32:21.695121Z","steps":["trace[26255207] 'process raft request' (duration: 751.533462ms)","trace[26255207] 'compare' (duration: 60.385136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:21.695334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:20.882987Z","time spent":"812.243207ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:21.695468Z","caller":"traceutil/trace.go:171","msg":"trace[1072342604] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"812.229537ms","start":"2026-03-20T05:32:20.883217Z","end":"2026-03-20T05:32:21.695447Z","steps":["trace[1072342604] 'process raft request' (duration: 811.854168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:21.695557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:20.8832Z","time spent":"812.316999ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:21.695524Z","caller":"traceutil/trace.go:171","msg":"trace[962615514] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:763; }","duration":"744.057921ms","start":"2026-03-20T05:32:20.951392Z","end":"2026-03-20T05:32:21.69545Z","steps":["trace[962615514] 'read index received' (duration: 520.136122ms)","trace[962615514] 'applied index is now lower than readState.Index' (duration: 223.918089ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:21.69564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.222565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:21.695744Z","caller":"traceutil/trace.go:171","msg":"trace[1505329733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"744.38992ms","start":"2026-03-20T05:32:20.951333Z","end":"2026-03-20T05:32:21.695723Z","steps":["trace[1505329733] 'agreement among raft nodes before linearized reading' (duration: 744.189944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:21.695776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.959491ms","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-03-20T05:32:21.69579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:20.95131Z","time spent":"744.473352ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:32:21.695812Z","caller":"traceutil/trace.go:171","msg":"trace[219118659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"689.056645ms","start":"2026-03-20T05:32:21.006745Z","end":"2026-03-20T05:32:21.695802Z","steps":["trace[219118659] 'agreement among raft nodes before linearized reading' (duration: 688.943722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:21.695836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:21.006717Z","time spent":"689.114896ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:32:22.019797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.930608ms","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-03-20T05:32:22.019904Z","caller":"traceutil/trace.go:171","msg":"trace[755395536] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:741; }","duration":"151.133774ms","start":"2026-03-20T05:32:21.868746Z","end":"2026-03-20T05:32:22.01988Z","steps":["trace[755395536] 'count revisions from in-memory index tree' (duration: 150.767664ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:22.122434Z","caller":"traceutil/trace.go:171","msg":"trace[1978420169] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"139.35112ms","start":"2026-03-20T05:32:21.983056Z","end":"2026-03-20T05:32:22.122407Z","steps":["trace[1978420169] 'process raft request' (duration: 139.150875ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:22.83728Z","caller":"traceutil/trace.go:171","msg":"trace[1571729760] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"205.190787ms","start":"2026-03-20T05:32:22.632049Z","end":"2026-03-20T05:32:22.83724Z","steps":["trace[1571729760] 'process raft request' (duration: 204.803637ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:23.02277Z","caller":"traceutil/trace.go:171","msg":"trace[2035600770] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"142.970093ms","start":"2026-03-20T05:32:22.879751Z","end":"2026-03-20T05:32:23.022721Z","steps":["trace[2035600770] 'process raft request' (duration: 142.425768ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:31.873846Z","caller":"traceutil/trace.go:171","msg":"trace[556909358] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"114.216478ms","start":"2026-03-20T05:32:31.759613Z","end":"2026-03-20T05:32:31.87383Z","steps":["trace[556909358] 'process raft request' (duration: 80.324876ms)","trace[556909358] 'compare' (duration: 33.599233ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:31.873817Z","caller":"traceutil/trace.go:171","msg":"trace[665757590] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"112.156475ms","start":"2026-03-20T05:32:31.761621Z","end":"2026-03-20T05:32:31.873778Z","steps":["trace[665757590] 'read index received' (duration: 2.319581ms)","trace[665757590] 'applied index is now lower than readState.Index' (duration: 109.835754ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:31.87406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.394761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T05:32:31.874113Z","caller":"traceutil/trace.go:171","msg":"trace[1780201103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"112.503833ms","start":"2026-03-20T05:32:31.761598Z","end":"2026-03-20T05:32:31.874102Z","steps":["trace[1780201103] 'agreement among raft nodes before linearized reading' (duration: 112.283548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:31.874291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.438911ms","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-03-20T05:32:31.87497Z","caller":"traceutil/trace.go:171","msg":"trace[2064987326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"112.572616ms","start":"2026-03-20T05:32:31.7618Z","end":"2026-03-20T05:32:31.874373Z","steps":["trace[2064987326] 'agreement among raft nodes before linearized reading' (duration: 112.277128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.092166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.8933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:38.092314Z","caller":"traceutil/trace.go:171","msg":"trace[1584539472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"139.095825ms","start":"2026-03-20T05:32:37.953182Z","end":"2026-03-20T05:32:38.092278Z","steps":["trace[1584539472] 'range keys from in-memory index tree' (duration: 138.752356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.092566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.652651ms","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-03-20T05:32:38.092672Z","caller":"traceutil/trace.go:171","msg":"trace[623837664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"121.792226ms","start":"2026-03-20T05:32:37.970849Z","end":"2026-03-20T05:32:38.092641Z","steps":["trace[623837664] 'range keys from in-memory index tree' (duration: 121.458616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.092735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.874284ms","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-03-20T05:32:38.092836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.156552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T05:32:38.092871Z","caller":"traceutil/trace.go:171","msg":"trace[1135376050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"118.066619ms","start":"2026-03-20T05:32:37.974776Z","end":"2026-03-20T05:32:38.092843Z","steps":["trace[1135376050] 'range keys from in-memory index tree' (duration: 117.667658ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:38.092943Z","caller":"traceutil/trace.go:171","msg":"trace[180563726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"160.337196ms","start":"2026-03-20T05:32:37.932581Z","end":"2026-03-20T05:32:38.092919Z","steps":["trace[180563726] 'range keys from in-memory index tree' (duration: 159.897116ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:38.202949Z","caller":"traceutil/trace.go:171","msg":"trace[1809355372] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"105.996304ms","start":"2026-03-20T05:32:38.096923Z","end":"2026-03-20T05:32:38.202919Z","steps":["trace[1809355372] 'process raft request' (duration: 105.795369ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:38.381644Z","caller":"traceutil/trace.go:171","msg":"trace[1229707866] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"283.732763ms","start":"2026-03-20T05:32:38.097875Z","end":"2026-03-20T05:32:38.381608Z","steps":["trace[1229707866] 'process raft request' (duration: 283.675081ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:38.381699Z","caller":"traceutil/trace.go:171","msg":"trace[1459569283] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"284.451881ms","start":"2026-03-20T05:32:38.097221Z","end":"2026-03-20T05:32:38.381673Z","steps":["trace[1459569283] 'process raft request' (duration: 280.94285ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:46.004562Z","caller":"traceutil/trace.go:171","msg":"trace[189105825] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"183.627733ms","start":"2026-03-20T05:32:45.820911Z","end":"2026-03-20T05:32:46.004538Z","steps":["trace[189105825] 'read index received' (duration: 183.478779ms)","trace[189105825] 'applied index is now lower than readState.Index' (duration: 148.244µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:46.004649Z","caller":"traceutil/trace.go:171","msg":"trace[1810877292] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"270.422158ms","start":"2026-03-20T05:32:45.734185Z","end":"2026-03-20T05:32:46.004607Z","steps":["trace[1810877292] 'process raft request' (duration: 270.1606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:46.004733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.788157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:32:46.005054Z","caller":"traceutil/trace.go:171","msg":"trace[1729631754] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:810; }","duration":"184.057223ms","start":"2026-03-20T05:32:45.820865Z","end":"2026-03-20T05:32:46.004922Z","steps":["trace[1729631754] 'agreement among raft nodes before linearized reading' (duration: 183.761526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:59.184713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.544917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:59.184812Z","caller":"traceutil/trace.go:171","msg":"trace[2100429854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"231.690381ms","start":"2026-03-20T05:32:58.953099Z","end":"2026-03-20T05:32:59.18479Z","steps":["trace[2100429854] 'range keys from in-memory index tree' (duration: 231.458234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:00.680788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257186519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:01.181432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257186519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:01.559501Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.40580265s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:01.559912Z","caller":"traceutil/trace.go:171","msg":"trace[2093336885] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"1.379669432s","start":"2026-03-20T05:33:00.180214Z","end":"2026-03-20T05:33:01.559883Z","steps":["trace[2093336885] 'read index received' (duration: 1.379514157s)","trace[2093336885] 'applied index is now lower than readState.Index' (duration: 154.245µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:01.560013Z","caller":"traceutil/trace.go:171","msg":"trace[1569985081] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.406366215s","start":"2026-03-20T05:33:00.153603Z","end":"2026-03-20T05:33:01.559969Z","steps":["trace[1569985081] 'process raft request' (duration: 1.406104478s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.560149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379912188s","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-03-20T05:33:01.560187Z","caller":"traceutil/trace.go:171","msg":"trace[1798885711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"1.3800239s","start":"2026-03-20T05:33:00.180151Z","end":"2026-03-20T05:33:01.560175Z","steps":["trace[1798885711] 'agreement among raft nodes before linearized reading' (duration: 1.379845946s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.560205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.153574Z","time spent":"1.406531799s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:01.560224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.180125Z","time spent":"1.380089802s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:33:01.856705Z","caller":"traceutil/trace.go:171","msg":"trace[1673106491] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"296.70645ms","start":"2026-03-20T05:33:01.559973Z","end":"2026-03-20T05:33:01.85668Z","steps":["trace[1673106491] 'read index received' (duration: 278.193869ms)","trace[1673106491] 'applied index is now lower than readState.Index' (duration: 18.511751ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:01.856779Z","caller":"traceutil/trace.go:171","msg":"trace[1991336721] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"634.527629ms","start":"2026-03-20T05:33:01.222218Z","end":"2026-03-20T05:33:01.856745Z","steps":["trace[1991336721] 'process raft request' (duration: 615.926485ms)","trace[1991336721] 'compare' (duration: 18.375847ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:01.856934Z","caller":"traceutil/trace.go:171","msg":"trace[2034404898] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"291.590368ms","start":"2026-03-20T05:33:01.565307Z","end":"2026-03-20T05:33:01.856897Z","steps":["trace[2034404898] 'process raft request' (duration: 291.341621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.857181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.057042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:01.857193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246940442s","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-03-20T05:33:01.857243Z","caller":"traceutil/trace.go:171","msg":"trace[1245304154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"904.179625ms","start":"2026-03-20T05:33:00.953046Z","end":"2026-03-20T05:33:01.857225Z","steps":["trace[1245304154] 'agreement among raft nodes before linearized reading' (duration: 903.667432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.857286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.953023Z","time spent":"904.253737ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:33:01.85728Z","caller":"traceutil/trace.go:171","msg":"trace[310044362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.247062235s","start":"2026-03-20T05:33:00.610195Z","end":"2026-03-20T05:33:01.857257Z","steps":["trace[310044362] 'agreement among raft nodes before linearized reading' (duration: 1.246600614s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.857327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439033665s","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-03-20T05:33:01.857331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.610184Z","time spent":"1.247137658s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:33:01.857366Z","caller":"traceutil/trace.go:171","msg":"trace[413788498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"1.439113007s","start":"2026-03-20T05:33:00.418241Z","end":"2026-03-20T05:33:01.857354Z","steps":["trace[413788498] 'agreement among raft nodes before linearized reading' (duration: 1.438546742s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.857394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.418224Z","time spent":"1.439163868s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:01.857406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248908653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T05:33:01.857465Z","caller":"traceutil/trace.go:171","msg":"trace[983189404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.249055367s","start":"2026-03-20T05:33:00.608392Z","end":"2026-03-20T05:33:01.857448Z","steps":["trace[983189404] 'agreement among raft nodes before linearized reading' (duration: 1.24841512s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:01.857514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.608364Z","time spent":"1.249138999s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:01.856924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:01.222188Z","time spent":"634.651112ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:15.831104Z","caller":"traceutil/trace.go:171","msg":"trace[835460556] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"209.947135ms","start":"2026-03-20T05:33:15.619549Z","end":"2026-03-20T05:33:15.829496Z","steps":["trace[835460556] 'process raft request' (duration: 209.632158ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:20.105453Z","caller":"traceutil/trace.go:171","msg":"trace[11838354] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"144.758741ms","start":"2026-03-20T05:33:19.960644Z","end":"2026-03-20T05:33:20.105414Z","steps":["trace[11838354] 'read index received' (duration: 144.556616ms)","trace[11838354] 'applied index is now lower than readState.Index' (duration: 201.185µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:20.105482Z","caller":"traceutil/trace.go:171","msg":"trace[1969453991] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"235.453679ms","start":"2026-03-20T05:33:19.869999Z","end":"2026-03-20T05:33:20.105452Z","steps":["trace[1969453991] 'process raft request' (duration: 235.265525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.105782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.113581ms","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-03-20T05:33:20.105846Z","caller":"traceutil/trace.go:171","msg":"trace[2064957928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"145.219073ms","start":"2026-03-20T05:33:19.96061Z","end":"2026-03-20T05:33:20.105829Z","steps":["trace[2064957928] 'agreement among raft nodes before linearized reading' (duration: 144.991257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.106171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.018951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-20T05:33:20.106163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.031283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T05:33:20.106208Z","caller":"traceutil/trace.go:171","msg":"trace[1049973579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"117.089603ms","start":"2026-03-20T05:33:19.989111Z","end":"2026-03-20T05:33:20.106201Z","steps":["trace[1049973579] 'agreement among raft nodes before linearized reading' (duration: 116.98278ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:20.106222Z","caller":"traceutil/trace.go:171","msg":"trace[781247363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"119.111125ms","start":"2026-03-20T05:33:19.9871Z","end":"2026-03-20T05:33:20.106211Z","steps":["trace[781247363] 'agreement among raft nodes before linearized reading' (duration: 118.940011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.106288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.291379ms","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-03-20T05:33:20.106383Z","caller":"traceutil/trace.go:171","msg":"trace[660180551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"119.434974ms","start":"2026-03-20T05:33:19.986926Z","end":"2026-03-20T05:33:20.106361Z","steps":["trace[660180551] 'agreement among raft nodes before linearized reading' (duration: 119.147357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.106563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.674145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:20.106614Z","caller":"traceutil/trace.go:171","msg":"trace[1341362235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"145.727707ms","start":"2026-03-20T05:33:19.960872Z","end":"2026-03-20T05:33:20.1066Z","steps":["trace[1341362235] 'agreement among raft nodes before linearized reading' (duration: 144.763312ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:20.41073Z","caller":"traceutil/trace.go:171","msg":"trace[909080552] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"300.018796ms","start":"2026-03-20T05:33:20.110687Z","end":"2026-03-20T05:33:20.410706Z","steps":["trace[909080552] 'process raft request' (duration: 204.551085ms)","trace[909080552] 'compare' (duration: 95.358379ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:20.411812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.110636Z","time spent":"300.15882ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:20.47592Z","caller":"traceutil/trace.go:171","msg":"trace[74603493] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"364.259595ms","start":"2026-03-20T05:33:20.111639Z","end":"2026-03-20T05:33:20.475898Z","steps":["trace[74603493] 'process raft request' (duration: 364.224024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.476065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.111628Z","time spent":"364.368218ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:20.476281Z","caller":"traceutil/trace.go:171","msg":"trace[1554551206] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"364.775569ms","start":"2026-03-20T05:33:20.111463Z","end":"2026-03-20T05:33:20.476239Z","steps":["trace[1554551206] 'process raft request' (duration: 364.241796ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:20.476396Z","caller":"traceutil/trace.go:171","msg":"trace[857824085] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"364.808989ms","start":"2026-03-20T05:33:20.111569Z","end":"2026-03-20T05:33:20.476378Z","steps":["trace[857824085] 'process raft request' (duration: 364.257265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.476471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.111559Z","time spent":"364.871611ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:20.476503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.111441Z","time spent":"364.949364ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:21.130693Z","caller":"traceutil/trace.go:171","msg":"trace[1609448046] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"175.720916ms","start":"2026-03-20T05:33:20.954922Z","end":"2026-03-20T05:33:21.130643Z","steps":["trace[1609448046] 'process raft request' (duration: 175.582573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:21.130786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.853647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:21.13065Z","caller":"traceutil/trace.go:171","msg":"trace[1187893034] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"153.711984ms","start":"2026-03-20T05:33:20.976916Z","end":"2026-03-20T05:33:21.130628Z","steps":["trace[1187893034] 'read index received' (duration: 153.536819ms)","trace[1187893034] 'applied index is now lower than readState.Index' (duration: 174.275µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:21.130845Z","caller":"traceutil/trace.go:171","msg":"trace[2071365782] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:979; }","duration":"153.965871ms","start":"2026-03-20T05:33:20.976866Z","end":"2026-03-20T05:33:21.130832Z","steps":["trace[2071365782] 'agreement among raft nodes before linearized reading' (duration: 153.833807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:21.546147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.227212ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:21.546241Z","caller":"traceutil/trace.go:171","msg":"trace[516430836] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:980; }","duration":"300.332305ms","start":"2026-03-20T05:33:21.245896Z","end":"2026-03-20T05:33:21.546228Z","steps":["trace[516430836] 'range keys from in-memory index tree' (duration: 300.211182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:21.546557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.513948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:21.54664Z","caller":"traceutil/trace.go:171","msg":"trace[685670205] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"410.897528ms","start":"2026-03-20T05:33:21.135726Z","end":"2026-03-20T05:33:21.546624Z","steps":["trace[685670205] 'process raft request' (duration: 309.162954ms)","trace[685670205] 'compare' (duration: 100.979634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:21.546698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.135696Z","time spent":"410.98784ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:33:21.834291Z","caller":"traceutil/trace.go:171","msg":"trace[1973076936] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"280.678023ms","start":"2026-03-20T05:33:21.55359Z","end":"2026-03-20T05:33:21.834268Z","steps":["trace[1973076936] 'process raft request' (duration: 280.619292ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:21.834538Z","caller":"traceutil/trace.go:171","msg":"trace[1033539335] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"282.817559ms","start":"2026-03-20T05:33:21.551672Z","end":"2026-03-20T05:33:21.83449Z","steps":["trace[1033539335] 'process raft request' (duration: 276.614198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.062902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.64729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:22.062968Z","caller":"traceutil/trace.go:171","msg":"trace[1374789689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"109.730182ms","start":"2026-03-20T05:33:21.953224Z","end":"2026-03-20T05:33:22.062954Z","steps":["trace[1374789689] 'range keys from in-memory index tree' (duration: 109.524956ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.409346Z","caller":"traceutil/trace.go:171","msg":"trace[1872948938] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"298.175719ms","start":"2026-03-20T05:33:22.111142Z","end":"2026-03-20T05:33:22.409318Z","steps":["trace[1872948938] 'read index received' (duration: 297.916431ms)","trace[1872948938] 'applied index is now lower than readState.Index' (duration: 258.198µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:22.4096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.434275ms","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-03-20T05:33:22.409644Z","caller":"traceutil/trace.go:171","msg":"trace[1765113653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"298.497346ms","start":"2026-03-20T05:33:22.111136Z","end":"2026-03-20T05:33:22.409633Z","steps":["trace[1765113653] 'agreement among raft nodes before linearized reading' (duration: 298.302372ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.410087Z","caller":"traceutil/trace.go:171","msg":"trace[1383644268] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"302.533411ms","start":"2026-03-20T05:33:22.107537Z","end":"2026-03-20T05:33:22.41007Z","steps":["trace[1383644268] 'process raft request' (duration: 301.602736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.410215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:22.107509Z","time spent":"302.629895ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:22.526642Z","caller":"traceutil/trace.go:171","msg":"trace[1021801905] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"101.833235ms","start":"2026-03-20T05:33:22.424787Z","end":"2026-03-20T05:33:22.52662Z","steps":["trace[1021801905] 'process raft request' (duration: 96.59889ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.779398Z","caller":"traceutil/trace.go:171","msg":"trace[503317006] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"246.221858ms","start":"2026-03-20T05:33:22.533153Z","end":"2026-03-20T05:33:22.779375Z","steps":["trace[503317006] 'process raft request' (duration: 244.650957ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.779419Z","caller":"traceutil/trace.go:171","msg":"trace[828137090] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"246.027253ms","start":"2026-03-20T05:33:22.533371Z","end":"2026-03-20T05:33:22.779399Z","steps":["trace[828137090] 'process raft request' (duration: 245.372706ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.044559Z","caller":"traceutil/trace.go:171","msg":"trace[1100903795] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1082; }","duration":"206.064469ms","start":"2026-03-20T05:33:28.838453Z","end":"2026-03-20T05:33:29.044518Z","steps":["trace[1100903795] 'read index received' (duration: 54.497175ms)","trace[1100903795] 'applied index is now lower than readState.Index' (duration: 151.565764ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:29.044633Z","caller":"traceutil/trace.go:171","msg":"trace[1986614598] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"232.246508ms","start":"2026-03-20T05:33:28.812338Z","end":"2026-03-20T05:33:29.044584Z","steps":["trace[1986614598] 'process raft request' (duration: 151.41808ms)","trace[1986614598] 'compare' (duration: 80.565152ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:29.044888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.400028ms","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-03-20T05:33:29.044942Z","caller":"traceutil/trace.go:171","msg":"trace[321241430] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1041; }","duration":"206.509221ms","start":"2026-03-20T05:33:28.838411Z","end":"2026-03-20T05:33:29.044921Z","steps":["trace[321241430] 'agreement among raft nodes before linearized reading' (duration: 206.261244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:30.864973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.776505ms","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-03-20T05:33:30.865078Z","caller":"traceutil/trace.go:171","msg":"trace[2138546328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"309.045032ms","start":"2026-03-20T05:33:30.556016Z","end":"2026-03-20T05:33:30.865061Z","steps":["trace[2138546328] 'range keys from in-memory index tree' (duration: 308.653382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:30.865107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.556006Z","time spent":"309.093974ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:33:30.864972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.01154ms","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-03-20T05:33:30.865242Z","caller":"traceutil/trace.go:171","msg":"trace[471559104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"203.316429ms","start":"2026-03-20T05:33:30.66192Z","end":"2026-03-20T05:33:30.865236Z","steps":["trace[471559104] 'range keys from in-memory index tree' (duration: 202.851905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:30.86498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.841073ms","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-03-20T05:33:30.865348Z","caller":"traceutil/trace.go:171","msg":"trace[1185480823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1045; }","duration":"310.232273ms","start":"2026-03-20T05:33:30.55511Z","end":"2026-03-20T05:33:30.865342Z","steps":["trace[1185480823] 'range keys from in-memory index tree' (duration: 309.697699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:30.865366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.555053Z","time spent":"310.307035ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:31.396894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019179257186827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:31.895436Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026687101s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:31.895989Z","caller":"traceutil/trace.go:171","msg":"trace[803039737] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.027291997s","start":"2026-03-20T05:33:30.868667Z","end":"2026-03-20T05:33:31.895959Z","steps":["trace[803039737] 'process raft request' (duration: 1.027101652s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:31.896143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.868651Z","time spent":"1.027413999s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:32.051821Z","caller":"traceutil/trace.go:171","msg":"trace[332468572] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1089; }","duration":"1.155600678s","start":"2026-03-20T05:33:30.896196Z","end":"2026-03-20T05:33:32.051797Z","steps":["trace[332468572] 'read index received' (duration: 999.459185ms)","trace[332468572] 'applied index is now lower than readState.Index' (duration: 156.140063ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:32.051925Z","caller":"traceutil/trace.go:171","msg":"trace[930472399] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.183181503s","start":"2026-03-20T05:33:30.868704Z","end":"2026-03-20T05:33:32.051886Z","steps":["trace[930472399] 'process raft request' (duration: 1.18304579s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:32.051934Z","caller":"traceutil/trace.go:171","msg":"trace[750870197] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"1.183169633s","start":"2026-03-20T05:33:30.868699Z","end":"2026-03-20T05:33:32.051868Z","steps":["trace[750870197] 'process raft request' (duration: 1.182867695s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:32.052076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.868679Z","time spent":"1.183318177s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:32.052094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155883885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T05:33:32.052137Z","caller":"traceutil/trace.go:171","msg":"trace[110222522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"1.155986957s","start":"2026-03-20T05:33:30.896139Z","end":"2026-03-20T05:33:32.052126Z","steps":["trace[110222522] 'agreement among raft nodes before linearized reading' (duration: 1.155787582s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:32.052187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.896117Z","time spent":"1.15606039s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:32.052257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:30.868651Z","time spent":"1.18346152s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:32.05214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004062753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T05:33:32.052325Z","caller":"traceutil/trace.go:171","msg":"trace[287538361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"1.00429189s","start":"2026-03-20T05:33:31.048023Z","end":"2026-03-20T05:33:32.052315Z","steps":["trace[287538361] 'agreement among raft nodes before linearized reading' (duration: 1.004002873s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:32.052356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.817256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-03-20T05:33:32.052377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:31.048004Z","time spent":"1.004363821s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:32.052436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.22419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-03-20T05:33:32.052459Z","caller":"traceutil/trace.go:171","msg":"trace[540251000] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1048; }","duration":"791.268871ms","start":"2026-03-20T05:33:31.261182Z","end":"2026-03-20T05:33:32.052451Z","steps":["trace[540251000] 'agreement among raft nodes before linearized reading' (duration: 791.21611ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:32.052386Z","caller":"traceutil/trace.go:171","msg":"trace[1788944799] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1048; }","duration":"204.877088ms","start":"2026-03-20T05:33:31.847498Z","end":"2026-03-20T05:33:32.052375Z","steps":["trace[1788944799] 'agreement among raft nodes before linearized reading' (duration: 204.805946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:32.052482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:31.26117Z","time spent":"791.306602ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-20T05:33:32.052541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.032649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:33:32.052591Z","caller":"traceutil/trace.go:171","msg":"trace[1793616906] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1048; }","duration":"817.111411ms","start":"2026-03-20T05:33:31.235461Z","end":"2026-03-20T05:33:32.052572Z","steps":["trace[1793616906] 'agreement among raft nodes before linearized reading' (duration: 817.04422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:32.052624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:31.235435Z","time spent":"817.177412ms","remote":"127.0.0.1:41706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:33:37.216896Z","caller":"traceutil/trace.go:171","msg":"trace[1076581140] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"103.389883ms","start":"2026-03-20T05:33:37.113469Z","end":"2026-03-20T05:33:37.216859Z","steps":["trace[1076581140] 'process raft request' (duration: 77.212704ms)","trace[1076581140] 'compare' (duration: 25.960904ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:48.322515Z","caller":"traceutil/trace.go:171","msg":"trace[2056730166] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"130.574839ms","start":"2026-03-20T05:33:48.191913Z","end":"2026-03-20T05:33:48.322488Z","steps":["trace[2056730166] 'process raft request' (duration: 62.362449ms)","trace[2056730166] 'compare' (duration: 67.937224ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:48.3226Z","caller":"traceutil/trace.go:171","msg":"trace[782729801] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"129.323997ms","start":"2026-03-20T05:33:48.193248Z","end":"2026-03-20T05:33:48.322572Z","steps":["trace[782729801] 'process raft request' (duration: 129.089581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:02.386113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.586636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-20T05:34:02.386403Z","caller":"traceutil/trace.go:171","msg":"trace[773018432] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1171; }","duration":"126.923574ms","start":"2026-03-20T05:34:02.259459Z","end":"2026-03-20T05:34:02.386383Z","steps":["trace[773018432] 'range keys from in-memory index tree' (duration: 126.440102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:35.181426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.164919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:35:35.182036Z","caller":"traceutil/trace.go:171","msg":"trace[40519553] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"139.845085ms","start":"2026-03-20T05:35:35.041822Z","end":"2026-03-20T05:35:35.181668Z","steps":["trace[40519553] 'process raft request' (duration: 139.727603ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:35.181636Z","caller":"traceutil/trace.go:171","msg":"trace[965952664] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"140.006979ms","start":"2026-03-20T05:35:35.041585Z","end":"2026-03-20T05:35:35.181592Z","steps":["trace[965952664] 'process raft request' (duration: 15.605323ms)","trace[965952664] 'compare' (duration: 124.062256ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:36:09.486557Z","caller":"traceutil/trace.go:171","msg":"trace[1834406525] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"138.636879ms","start":"2026-03-20T05:36:09.347891Z","end":"2026-03-20T05:36:09.486528Z","steps":["trace[1834406525] 'process raft request' (duration: 137.340365ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:09.486573Z","caller":"traceutil/trace.go:171","msg":"trace[118916677] linearizableReadLoop","detail":"{readStateIndex:1769; appliedIndex:1767; }","duration":"137.245783ms","start":"2026-03-20T05:36:09.349297Z","end":"2026-03-20T05:36:09.486543Z","steps":["trace[118916677] 'read index received' (duration: 135.94196ms)","trace[118916677] 'applied index is now lower than readState.Index' (duration: 1.302633ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:36:09.486576Z","caller":"traceutil/trace.go:171","msg":"trace[1960534092] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"137.927272ms","start":"2026-03-20T05:36:09.34863Z","end":"2026-03-20T05:36:09.486557Z","steps":["trace[1960534092] 'process raft request' (duration: 137.829839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:36:09.486946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-20T05:36:09.487117Z","caller":"traceutil/trace.go:171","msg":"trace[1056723466] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1693; }","duration":"137.810388ms","start":"2026-03-20T05:36:09.34927Z","end":"2026-03-20T05:36:09.487081Z","steps":["trace[1056723466] 'agreement among raft nodes before linearized reading' (duration: 137.459199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:36:09.487558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.547378ms","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-03-20T05:36:09.487652Z","caller":"traceutil/trace.go:171","msg":"trace[93810931] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1693; }","duration":"112.687042ms","start":"2026-03-20T05:36:09.374944Z","end":"2026-03-20T05:36:09.487631Z","steps":["trace[93810931] 'agreement among raft nodes before linearized reading' (duration: 111.805638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:36:12.960039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.685037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-20T05:36:12.960119Z","caller":"traceutil/trace.go:171","msg":"trace[2895604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1704; }","duration":"125.8011ms","start":"2026-03-20T05:36:12.834299Z","end":"2026-03-20T05:36:12.9601Z","steps":["trace[2895604] 'range keys from in-memory index tree' (duration: 125.549433ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:39.72598Z","caller":"traceutil/trace.go:171","msg":"trace[51520816] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"151.404788ms","start":"2026-03-20T05:36:39.574504Z","end":"2026-03-20T05:36:39.725909Z","steps":["trace[51520816] 'process raft request' (duration: 151.166802ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:39.893147Z","caller":"traceutil/trace.go:171","msg":"trace[1890099523] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"162.36852ms","start":"2026-03-20T05:36:39.730736Z","end":"2026-03-20T05:36:39.893105Z","steps":["trace[1890099523] 'process raft request' (duration: 161.916659ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:39.894307Z","caller":"traceutil/trace.go:171","msg":"trace[889063347] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"163.432758ms","start":"2026-03-20T05:36:39.730828Z","end":"2026-03-20T05:36:39.894261Z","steps":["trace[889063347] 'process raft request' (duration: 162.983746ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:39.894155Z","caller":"traceutil/trace.go:171","msg":"trace[1297087270] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"160.888422ms","start":"2026-03-20T05:36:39.733221Z","end":"2026-03-20T05:36:39.894109Z","steps":["trace[1297087270] 'process raft request' (duration: 160.807561ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:49.375333Z","caller":"traceutil/trace.go:171","msg":"trace[667859554] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"123.366419ms","start":"2026-03-20T05:36:49.251925Z","end":"2026-03-20T05:36:49.375292Z","steps":["trace[667859554] 'process raft request' (duration: 123.098745ms)"],"step_count":1}