{"level":"warn","ts":"2026-04-28T06:04:18.055178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:04:18.055316Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-04-28T06:04:18.055441Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:04:18.055464Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-28T06:04:18.055503Z","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-04-28T06:04:18.058124Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-04-28T06:04:18.058396Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-04-28T06:04:18.062777Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.913068ms"} {"level":"info","ts":"2026-04-28T06:04:18.070832Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-04-28T06:04:18.07093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:04:18.070961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-04-28T06:04:18.070976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:04:18.070993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-04-28T06:04:18.071029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-04-28T06:04:18.077827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:04:18.079061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:04:18.080062Z","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-04-28T06:04:18.082055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:04:18.082511Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:04:18.082615Z","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-04-28T06:04:18.082659Z","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-04-28T06:04:18.082668Z","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-04-28T06:04:18.085528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-04-28T06:04:18.08611Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-28T06:04:18.087914Z","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-04-28T06:04:18.087978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-28T06:04:18.088007Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-28T06:04:18.088145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:04:18.088169Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:04:18.272068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:04:18.272216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:04:18.272273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-04-28T06:04:18.272319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:04:18.272366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-28T06:04:18.272415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-04-28T06:04:18.27249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-28T06:04:18.273287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:18.274166Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:04:18.27427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:04:18.274175Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:04:18.275211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:18.276403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:18.276603Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:18.276538Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:04:18.276687Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:04:18.278009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:04:18.278746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-04-28T06:04:49.001815Z","caller":"traceutil/trace.go:171","msg":"trace[1921825656] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"275.683125ms","start":"2026-04-28T06:04:48.726109Z","end":"2026-04-28T06:04:49.001792Z","steps":["trace[1921825656] 'process raft request' (duration: 275.574853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008095Z","caller":"traceutil/trace.go:171","msg":"trace[1881135123] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"271.833169ms","start":"2026-04-28T06:04:48.736238Z","end":"2026-04-28T06:04:49.008071Z","steps":["trace[1881135123] 'process raft request' (duration: 271.693246ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.00825Z","caller":"traceutil/trace.go:171","msg":"trace[1414556869] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"279.890671ms","start":"2026-04-28T06:04:48.72835Z","end":"2026-04-28T06:04:49.00824Z","steps":["trace[1414556869] 'process raft request' (duration: 279.508361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008417Z","caller":"traceutil/trace.go:171","msg":"trace[1595960632] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"260.093547ms","start":"2026-04-28T06:04:48.748314Z","end":"2026-04-28T06:04:49.008408Z","steps":["trace[1595960632] 'process raft request' (duration: 259.739488ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008525Z","caller":"traceutil/trace.go:171","msg":"trace[1881577226] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"257.720537ms","start":"2026-04-28T06:04:48.750798Z","end":"2026-04-28T06:04:49.008519Z","steps":["trace[1881577226] 'process raft request' (duration: 257.338688ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008499Z","caller":"traceutil/trace.go:171","msg":"trace[1740111856] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"263.833239ms","start":"2026-04-28T06:04:48.744629Z","end":"2026-04-28T06:04:49.008462Z","steps":["trace[1740111856] 'process raft request' (duration: 263.349637ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008649Z","caller":"traceutil/trace.go:171","msg":"trace[1076607708] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"260.400054ms","start":"2026-04-28T06:04:48.748237Z","end":"2026-04-28T06:04:49.008637Z","steps":["trace[1076607708] 'process raft request' (duration: 259.776679ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.008735Z","caller":"traceutil/trace.go:171","msg":"trace[1112496422] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"256.22385ms","start":"2026-04-28T06:04:48.752416Z","end":"2026-04-28T06:04:49.00864Z","steps":["trace[1112496422] 'process raft request' (duration: 255.845131ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.009132Z","caller":"traceutil/trace.go:171","msg":"trace[593447743] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"254.770824ms","start":"2026-04-28T06:04:48.75433Z","end":"2026-04-28T06:04:49.009101Z","steps":["trace[593447743] 'process raft request' (duration: 254.037326ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.009466Z","caller":"traceutil/trace.go:171","msg":"trace[96285472] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"231.203336ms","start":"2026-04-28T06:04:48.778216Z","end":"2026-04-28T06:04:49.009419Z","steps":["trace[96285472] 'process raft request' (duration: 230.895198ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.009608Z","caller":"traceutil/trace.go:171","msg":"trace[1060514512] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"253.650196ms","start":"2026-04-28T06:04:48.755944Z","end":"2026-04-28T06:04:49.009594Z","steps":["trace[1060514512] 'process raft request' (duration: 253.093062ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.009728Z","caller":"traceutil/trace.go:171","msg":"trace[2003660112] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"116.613728ms","start":"2026-04-28T06:04:48.893089Z","end":"2026-04-28T06:04:49.009702Z","steps":["trace[2003660112] 'process raft request' (duration: 116.136736ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.670071Z","caller":"traceutil/trace.go:171","msg":"trace[383176006] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"484.003791ms","start":"2026-04-28T06:04:49.186044Z","end":"2026-04-28T06:04:49.670048Z","steps":["trace[383176006] 'process raft request' (duration: 483.861617ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:49.670164Z","caller":"traceutil/trace.go:171","msg":"trace[255078868] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"540.453749ms","start":"2026-04-28T06:04:49.129672Z","end":"2026-04-28T06:04:49.670126Z","steps":["trace[255078868] 'process raft request' (duration: 539.865074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:49.670597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:49.186017Z","time spent":"484.125024ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:04:49.670927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:49.129653Z","time spent":"540.598493ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:04:49.690026Z","caller":"traceutil/trace.go:171","msg":"trace[1762254827] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:526; }","duration":"411.900502ms","start":"2026-04-28T06:04:49.278102Z","end":"2026-04-28T06:04:49.690003Z","steps":["trace[1762254827] 'read index received' (duration: 391.316029ms)","trace[1762254827] 'applied index is now lower than readState.Index' (duration: 20.583553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:04:49.690184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.061767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:49.690227Z","caller":"traceutil/trace.go:171","msg":"trace[343463110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"412.156349ms","start":"2026-04-28T06:04:49.278061Z","end":"2026-04-28T06:04:49.690218Z","steps":["trace[343463110] 'agreement among raft nodes before linearized reading' (duration: 412.040106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:49.690266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:49.278043Z","time spent":"412.214461ms","remote":"127.0.0.1:56858","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-04-28T06:04:49.690855Z","caller":"traceutil/trace.go:171","msg":"trace[473597506] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"485.17216ms","start":"2026-04-28T06:04:49.205661Z","end":"2026-04-28T06:04:49.690833Z","steps":["trace[473597506] 'process raft request' (duration: 484.237387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:49.690971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:49.205638Z","time spent":"485.266363ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:04:49.691081Z","caller":"traceutil/trace.go:171","msg":"trace[1980970929] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"487.032806ms","start":"2026-04-28T06:04:49.204041Z","end":"2026-04-28T06:04:49.691074Z","steps":["trace[1980970929] 'process raft request' (duration: 485.691723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:49.691112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:49.204025Z","time spent":"487.070936ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:04:50.760185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.816184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-28T06:04:50.760243Z","caller":"traceutil/trace.go:171","msg":"trace[1777652227] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:550; }","duration":"106.911057ms","start":"2026-04-28T06:04:50.653319Z","end":"2026-04-28T06:04:50.76023Z","steps":["trace[1777652227] 'range keys from in-memory index tree' (duration: 106.700881ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:55.233468Z","caller":"traceutil/trace.go:171","msg":"trace[2135356203] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"198.408319ms","start":"2026-04-28T06:04:55.035034Z","end":"2026-04-28T06:04:55.233442Z","steps":["trace[2135356203] 'process raft request' (duration: 198.242995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:55.536043Z","caller":"traceutil/trace.go:171","msg":"trace[198323696] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"110.821034ms","start":"2026-04-28T06:04:55.425204Z","end":"2026-04-28T06:04:55.536025Z","steps":["trace[198323696] 'process raft request' (duration: 110.264421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.646281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.925193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:04:56.646412Z","caller":"traceutil/trace.go:171","msg":"trace[1904606965] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.054638622s","start":"2026-04-28T06:04:55.591739Z","end":"2026-04-28T06:04:56.646378Z","steps":["trace[1904606965] 'process raft request' (duration: 901.390311ms)","trace[1904606965] 'compare' (duration: 152.79432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:04:56.646551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:55.591669Z","time spent":"1.054816786s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:04:56.646436Z","caller":"traceutil/trace.go:171","msg":"trace[1553700990] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"510.89121ms","start":"2026-04-28T06:04:56.135514Z","end":"2026-04-28T06:04:56.646405Z","steps":["trace[1553700990] 'read index received' (duration: 357.509656ms)","trace[1553700990] 'applied index is now lower than readState.Index' (duration: 153.378764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:04:56.658158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.654864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T06:04:56.65829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.413972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:56.658335Z","caller":"traceutil/trace.go:171","msg":"trace[822208688] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"150.464162ms","start":"2026-04-28T06:04:56.507852Z","end":"2026-04-28T06:04:56.658316Z","steps":["trace[822208688] 'agreement among raft nodes before linearized reading' (duration: 150.417991ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:56.658361Z","caller":"traceutil/trace.go:171","msg":"trace[1336310561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"522.86152ms","start":"2026-04-28T06:04:56.135442Z","end":"2026-04-28T06:04:56.658303Z","steps":["trace[1336310561] 'agreement among raft nodes before linearized reading' (duration: 511.144937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:56.135399Z","time spent":"522.984863ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:04:56.658483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.113597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:56.658561Z","caller":"traceutil/trace.go:171","msg":"trace[899415164] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"179.199999ms","start":"2026-04-28T06:04:56.479343Z","end":"2026-04-28T06:04:56.658543Z","steps":["trace[899415164] 'agreement among raft nodes before linearized reading' (duration: 179.093186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.721119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:04:56.658595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.518552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:04:56.658599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.305872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:56.658649Z","caller":"traceutil/trace.go:171","msg":"trace[648244231] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"150.828291ms","start":"2026-04-28T06:04:56.507809Z","end":"2026-04-28T06:04:56.658637Z","steps":["trace[648244231] 'agreement among raft nodes before linearized reading' (duration: 150.687508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.82032ms","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-04-28T06:04:56.658674Z","caller":"traceutil/trace.go:171","msg":"trace[2074623006] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"208.607394ms","start":"2026-04-28T06:04:56.450042Z","end":"2026-04-28T06:04:56.65865Z","steps":["trace[2074623006] 'agreement among raft nodes before linearized reading' (duration: 208.492471ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:56.658702Z","caller":"traceutil/trace.go:171","msg":"trace[349420581] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"179.371644ms","start":"2026-04-28T06:04:56.479281Z","end":"2026-04-28T06:04:56.658653Z","steps":["trace[349420581] 'agreement among raft nodes before linearized reading' (duration: 179.294572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.970879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:04:56.65879Z","caller":"traceutil/trace.go:171","msg":"trace[543891655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"490.031691ms","start":"2026-04-28T06:04:56.168752Z","end":"2026-04-28T06:04:56.658784Z","steps":["trace[543891655] 'agreement among raft nodes before linearized reading' (duration: 489.951089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:56.168733Z","time spent":"490.071582ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:04:56.658834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.28001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:04:56.658179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.627623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:04:56.658921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.046823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T06:04:56.658963Z","caller":"traceutil/trace.go:171","msg":"trace[656622423] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"209.446784ms","start":"2026-04-28T06:04:56.449509Z","end":"2026-04-28T06:04:56.658956Z","steps":["trace[656622423] 'agreement among raft nodes before linearized reading' (duration: 208.599692ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:56.65897Z","caller":"traceutil/trace.go:171","msg":"trace[1503249913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"379.459154ms","start":"2026-04-28T06:04:56.279461Z","end":"2026-04-28T06:04:56.65892Z","steps":["trace[1503249913] 'agreement among raft nodes before linearized reading' (duration: 379.223118ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:56.659001Z","caller":"traceutil/trace.go:171","msg":"trace[391540391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"480.161365ms","start":"2026-04-28T06:04:56.178805Z","end":"2026-04-28T06:04:56.658966Z","steps":["trace[391540391] 'agreement among raft nodes before linearized reading' (duration: 479.850808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.658251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.486869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:56.659099Z","caller":"traceutil/trace.go:171","msg":"trace[1691958921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"150.36668ms","start":"2026-04-28T06:04:56.508716Z","end":"2026-04-28T06:04:56.659083Z","steps":["trace[1691958921] 'agreement among raft nodes before linearized reading' (duration: 149.513399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.659044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:56.279448Z","time spent":"379.583707ms","remote":"127.0.0.1:56860","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-04-28T06:04:56.658685Z","caller":"traceutil/trace.go:171","msg":"trace[743324290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"516.893482ms","start":"2026-04-28T06:04:56.141782Z","end":"2026-04-28T06:04:56.658676Z","steps":["trace[743324290] 'agreement among raft nodes before linearized reading' (duration: 516.829501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:56.659211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:56.141755Z","time spent":"517.448865ms","remote":"127.0.0.1:57102","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-04-28T06:04:56.659087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:04:56.178783Z","time spent":"480.294069ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:04:56.659287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.037463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:04:56.65931Z","caller":"traceutil/trace.go:171","msg":"trace[20438830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:582; }","duration":"209.844984ms","start":"2026-04-28T06:04:56.449457Z","end":"2026-04-28T06:04:56.659302Z","steps":["trace[20438830] 'agreement among raft nodes before linearized reading' (duration: 209.053094ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:12.054916Z","caller":"traceutil/trace.go:171","msg":"trace[604455075] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"156.874912ms","start":"2026-04-28T06:05:11.898014Z","end":"2026-04-28T06:05:12.054889Z","steps":["trace[604455075] 'process raft request' (duration: 156.720719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:39.793085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.805626ms","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-04-28T06:05:39.793199Z","caller":"traceutil/trace.go:171","msg":"trace[110844364] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:934; }","duration":"178.97087ms","start":"2026-04-28T06:05:39.614206Z","end":"2026-04-28T06:05:39.793177Z","steps":["trace[110844364] 'count revisions from in-memory index tree' (duration: 178.714433ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:41.477146Z","caller":"traceutil/trace.go:171","msg":"trace[1997024374] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"109.391651ms","start":"2026-04-28T06:05:41.367734Z","end":"2026-04-28T06:05:41.477126Z","steps":["trace[1997024374] 'process raft request' (duration: 108.273324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.991303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123201449694,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:05:44.491491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123201449694,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:05:44.654103Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172585852s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:05:44.654417Z","caller":"traceutil/trace.go:171","msg":"trace[102848436] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.172983393s","start":"2026-04-28T06:05:43.481418Z","end":"2026-04-28T06:05:44.654401Z","steps":["trace[102848436] 'process raft request' (duration: 1.17287225s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.65454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.481394Z","time spent":"1.173077005s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:05:44.657317Z","caller":"traceutil/trace.go:171","msg":"trace[1181402750] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"1.166239544s","start":"2026-04-28T06:05:43.49106Z","end":"2026-04-28T06:05:44.6573Z","steps":["trace[1181402750] 'read index received' (duration: 1.163326562s)","trace[1181402750] 'applied index is now lower than readState.Index' (duration: 2.912242ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:44.657357Z","caller":"traceutil/trace.go:171","msg":"trace[1915055889] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"385.23285ms","start":"2026-04-28T06:05:44.272111Z","end":"2026-04-28T06:05:44.657344Z","steps":["trace[1915055889] 'process raft request' (duration: 385.111997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.657413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:44.272089Z","time spent":"385.296592ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:05:44.657467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166391378s","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-04-28T06:05:44.657507Z","caller":"traceutil/trace.go:171","msg":"trace[361513650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"1.16648294s","start":"2026-04-28T06:05:43.491014Z","end":"2026-04-28T06:05:44.657497Z","steps":["trace[361513650] 'agreement among raft nodes before linearized reading' (duration: 1.166357387s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.657534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.491002Z","time spent":"1.166525602s","remote":"127.0.0.1:57102","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-04-28T06:05:44.657572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165193738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:05:44.657643Z","caller":"traceutil/trace.go:171","msg":"trace[1093548642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.165280491s","start":"2026-04-28T06:05:43.492353Z","end":"2026-04-28T06:05:44.657633Z","steps":["trace[1093548642] 'agreement among raft nodes before linearized reading' (duration: 1.165140497s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.657665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166546552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T06:05:44.657678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.492343Z","time spent":"1.165325571s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:05:44.657689Z","caller":"traceutil/trace.go:171","msg":"trace[1636582008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"1.166610964s","start":"2026-04-28T06:05:43.49107Z","end":"2026-04-28T06:05:44.657681Z","steps":["trace[1636582008] 'agreement among raft nodes before linearized reading' (duration: 1.166543062s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.657711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.491049Z","time spent":"1.166658285s","remote":"127.0.0.1:57102","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-04-28T06:05:44.657518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.094487ms","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-04-28T06:05:44.657838Z","caller":"traceutil/trace.go:171","msg":"trace[1927100276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"397.432915ms","start":"2026-04-28T06:05:44.260377Z","end":"2026-04-28T06:05:44.65781Z","steps":["trace[1927100276] 'agreement among raft nodes before linearized reading' (duration: 397.020165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.659378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:44.260362Z","time spent":"398.978314ms","remote":"127.0.0.1:57102","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-04-28T06:05:44.657475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166368707s","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-04-28T06:05:44.659483Z","caller":"traceutil/trace.go:171","msg":"trace[817869712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.168411158s","start":"2026-04-28T06:05:43.491054Z","end":"2026-04-28T06:05:44.659466Z","steps":["trace[817869712] 'agreement among raft nodes before linearized reading' (duration: 1.166321946s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.659549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.491035Z","time spent":"1.16849877s","remote":"127.0.0.1:57102","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-04-28T06:06:13.424766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.866935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:13.424875Z","caller":"traceutil/trace.go:171","msg":"trace[1905510752] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"218.163468ms","start":"2026-04-28T06:06:13.206693Z","end":"2026-04-28T06:06:13.424856Z","steps":["trace[1905510752] 'read index received' (duration: 44.781µs)","trace[1905510752] 'applied index is now lower than readState.Index' (duration: 218.117627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:13.424964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.271732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:13.424993Z","caller":"traceutil/trace.go:171","msg":"trace[1985385211] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1046; }","duration":"218.341192ms","start":"2026-04-28T06:06:13.206641Z","end":"2026-04-28T06:06:13.424982Z","steps":["trace[1985385211] 'agreement among raft nodes before linearized reading' (duration: 218.283011ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:13.42498Z","caller":"traceutil/trace.go:171","msg":"trace[1299804013] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"531.292639ms","start":"2026-04-28T06:06:12.893655Z","end":"2026-04-28T06:06:13.424948Z","steps":["trace[1299804013] 'process raft request' (duration: 59.158797ms)","trace[1299804013] 'compare' (duration: 471.744892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:13.425127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:12.893632Z","time spent":"531.414061ms","remote":"127.0.0.1:57102","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-04-28T06:06:15.017117Z","caller":"traceutil/trace.go:171","msg":"trace[1300198750] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"171.320429ms","start":"2026-04-28T06:06:14.845766Z","end":"2026-04-28T06:06:15.017086Z","steps":["trace[1300198750] 'process raft request' (duration: 171.142694ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:15.056008Z","caller":"traceutil/trace.go:171","msg":"trace[628201523] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"210.150918ms","start":"2026-04-28T06:06:14.845829Z","end":"2026-04-28T06:06:15.05598Z","steps":["trace[628201523] 'process raft request' (duration: 206.282932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:15.056021Z","caller":"traceutil/trace.go:171","msg":"trace[744578879] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"209.465841ms","start":"2026-04-28T06:06:14.846528Z","end":"2026-04-28T06:06:15.055994Z","steps":["trace[744578879] 'process raft request' (duration: 209.356589ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:15.056466Z","caller":"traceutil/trace.go:171","msg":"trace[1801416344] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"209.242996ms","start":"2026-04-28T06:06:14.847177Z","end":"2026-04-28T06:06:15.05642Z","steps":["trace[1801416344] 'process raft request' (duration: 208.773154ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:17.062328Z","caller":"traceutil/trace.go:171","msg":"trace[477126396] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"215.164002ms","start":"2026-04-28T06:06:16.847142Z","end":"2026-04-28T06:06:17.062306Z","steps":["trace[477126396] 'process raft request' (duration: 214.830933ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:17.213007Z","caller":"traceutil/trace.go:171","msg":"trace[201855132] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"146.145199ms","start":"2026-04-28T06:06:17.066836Z","end":"2026-04-28T06:06:17.212981Z","steps":["trace[201855132] 'process raft request' (duration: 141.509392ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:17.218832Z","caller":"traceutil/trace.go:171","msg":"trace[462974772] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"151.80849ms","start":"2026-04-28T06:06:17.067001Z","end":"2026-04-28T06:06:17.21881Z","steps":["trace[462974772] 'process raft request' (duration: 151.589115ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:17.218831Z","caller":"traceutil/trace.go:171","msg":"trace[1583140967] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"151.619665ms","start":"2026-04-28T06:06:17.067187Z","end":"2026-04-28T06:06:17.218807Z","steps":["trace[1583140967] 'process raft request' (duration: 151.528633ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:17.219013Z","caller":"traceutil/trace.go:171","msg":"trace[1362465391] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"151.42434ms","start":"2026-04-28T06:06:17.067565Z","end":"2026-04-28T06:06:17.218989Z","steps":["trace[1362465391] 'process raft request' (duration: 151.184975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.779974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123201450187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:06:29.822784Z","caller":"traceutil/trace.go:171","msg":"trace[397583182] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"543.077582ms","start":"2026-04-28T06:06:29.279685Z","end":"2026-04-28T06:06:29.822763Z","steps":["trace[397583182] 'read index received' (duration: 542.896327ms)","trace[397583182] 'applied index is now lower than readState.Index' (duration: 180.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:29.822916Z","caller":"traceutil/trace.go:171","msg":"trace[227397324] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"711.1718ms","start":"2026-04-28T06:06:29.111711Z","end":"2026-04-28T06:06:29.822883Z","steps":["trace[227397324] 'process raft request' (duration: 710.925744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.822928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.230546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:29.822984Z","caller":"traceutil/trace.go:171","msg":"trace[1329750947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"543.334259ms","start":"2026-04-28T06:06:29.279639Z","end":"2026-04-28T06:06:29.822973Z","steps":["trace[1329750947] 'agreement among raft nodes before linearized reading' (duration: 543.232356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.173165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T06:06:29.823017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.279621Z","time spent":"543.38803ms","remote":"127.0.0.1:56860","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-04-28T06:06:29.823036Z","caller":"traceutil/trace.go:171","msg":"trace[21612975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1143; }","duration":"535.221806ms","start":"2026-04-28T06:06:29.287808Z","end":"2026-04-28T06:06:29.82303Z","steps":["trace[21612975] 'agreement among raft nodes before linearized reading' (duration: 535.139504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.287796Z","time spent":"535.251977ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:06:29.823079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.111691Z","time spent":"711.292132ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:06:29.823152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.243111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:06:29.823168Z","caller":"traceutil/trace.go:171","msg":"trace[64491392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1143; }","duration":"321.284593ms","start":"2026-04-28T06:06:29.501879Z","end":"2026-04-28T06:06:29.823164Z","steps":["trace[64491392] 'agreement among raft nodes before linearized reading' (duration: 321.245312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.501862Z","time spent":"321.314623ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:06:29.823248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.670668ms","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-04-28T06:06:29.82326Z","caller":"traceutil/trace.go:171","msg":"trace[1231934942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1143; }","duration":"497.701468ms","start":"2026-04-28T06:06:29.325554Z","end":"2026-04-28T06:06:29.823256Z","steps":["trace[1231934942] 'agreement among raft nodes before linearized reading' (duration: 497.660847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.325541Z","time spent":"497.729069ms","remote":"127.0.0.1:57102","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-04-28T06:06:29.823328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.878433ms","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-04-28T06:06:29.823338Z","caller":"traceutil/trace.go:171","msg":"trace[1201916275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1143; }","duration":"519.903723ms","start":"2026-04-28T06:06:29.303431Z","end":"2026-04-28T06:06:29.823335Z","steps":["trace[1201916275] 'agreement among raft nodes before linearized reading' (duration: 519.872282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.735855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T06:06:29.823349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.303423Z","time spent":"519.923543ms","remote":"127.0.0.1:57102","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":"info","ts":"2026-04-28T06:06:29.823365Z","caller":"traceutil/trace.go:171","msg":"trace[454269168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1143; }","duration":"520.784485ms","start":"2026-04-28T06:06:29.302571Z","end":"2026-04-28T06:06:29.823356Z","steps":["trace[454269168] 'agreement among raft nodes before linearized reading' (duration: 520.698293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:29.823395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:29.302559Z","time spent":"520.829846ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:06:45.926548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.953525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:45.926679Z","caller":"traceutil/trace.go:171","msg":"trace[1479384169] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"228.038233ms","start":"2026-04-28T06:06:45.698621Z","end":"2026-04-28T06:06:45.926659Z","steps":["trace[1479384169] 'read index received' (duration: 19.922635ms)","trace[1479384169] 'applied index is now lower than readState.Index' (duration: 208.114018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:45.926974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.32306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qpqg7\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-04-28T06:06:45.927029Z","caller":"traceutil/trace.go:171","msg":"trace[1023457211] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qpqg7; range_end:; response_count:1; response_revision:1211; }","duration":"228.433733ms","start":"2026-04-28T06:06:45.69858Z","end":"2026-04-28T06:06:45.927014Z","steps":["trace[1023457211] 'agreement among raft nodes before linearized reading' (duration: 228.218018ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:45.927539Z","caller":"traceutil/trace.go:171","msg":"trace[1327546972] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"362.508443ms","start":"2026-04-28T06:06:45.564185Z","end":"2026-04-28T06:06:45.926693Z","steps":["trace[1327546972] 'process raft request' (duration: 154.313232ms)","trace[1327546972] 'compare' (duration: 207.39579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:45.927953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:45.564172Z","time spent":"363.450436ms","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:06:45.974858Z","caller":"traceutil/trace.go:171","msg":"trace[636347115] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"103.804998ms","start":"2026-04-28T06:06:45.871015Z","end":"2026-04-28T06:06:45.97482Z","steps":["trace[636347115] 'process raft request' (duration: 103.49757ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.143231Z","caller":"traceutil/trace.go:171","msg":"trace[1741758947] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"171.57312ms","start":"2026-04-28T06:06:45.971634Z","end":"2026-04-28T06:06:46.143207Z","steps":["trace[1741758947] 'process raft request' (duration: 171.515539ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.143553Z","caller":"traceutil/trace.go:171","msg":"trace[1854538092] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"201.308019ms","start":"2026-04-28T06:06:45.942164Z","end":"2026-04-28T06:06:46.143472Z","steps":["trace[1854538092] 'process raft request' (duration: 200.391236ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.199849Z","caller":"traceutil/trace.go:171","msg":"trace[1398833489] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1265; }","duration":"162.821123ms","start":"2026-04-28T06:06:46.037007Z","end":"2026-04-28T06:06:46.199828Z","steps":["trace[1398833489] 'read index received' (duration: 106.259609ms)","trace[1398833489] 'applied index is now lower than readState.Index' (duration: 56.560284ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:46.199853Z","caller":"traceutil/trace.go:171","msg":"trace[217152683] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"220.289039ms","start":"2026-04-28T06:06:45.979526Z","end":"2026-04-28T06:06:46.199815Z","steps":["trace[217152683] 'process raft request' (duration: 220.085814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:46.200009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.955917ms","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-04-28T06:06:46.200044Z","caller":"traceutil/trace.go:171","msg":"trace[1048950386] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1219; }","duration":"163.08049ms","start":"2026-04-28T06:06:46.036953Z","end":"2026-04-28T06:06:46.200033Z","steps":["trace[1048950386] 'agreement among raft nodes before linearized reading' (duration: 162.951107ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.200276Z","caller":"traceutil/trace.go:171","msg":"trace[1970422188] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"220.576776ms","start":"2026-04-28T06:06:45.979691Z","end":"2026-04-28T06:06:46.200267Z","steps":["trace[1970422188] 'process raft request' (duration: 220.044153ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.200389Z","caller":"traceutil/trace.go:171","msg":"trace[1374001538] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"219.637683ms","start":"2026-04-28T06:06:45.980736Z","end":"2026-04-28T06:06:46.200374Z","steps":["trace[1374001538] 'process raft request' (duration: 219.046479ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.200476Z","caller":"traceutil/trace.go:171","msg":"trace[1436258076] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"219.659004ms","start":"2026-04-28T06:06:45.980808Z","end":"2026-04-28T06:06:46.200467Z","steps":["trace[1436258076] 'process raft request' (duration: 218.994608ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.200552Z","caller":"traceutil/trace.go:171","msg":"trace[2001607940] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"220.069065ms","start":"2026-04-28T06:06:45.980472Z","end":"2026-04-28T06:06:46.200541Z","steps":["trace[2001607940] 'process raft request' (duration: 219.293055ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.277844Z","caller":"traceutil/trace.go:171","msg":"trace[219641262] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"121.272351ms","start":"2026-04-28T06:06:46.156548Z","end":"2026-04-28T06:06:46.27782Z","steps":["trace[219641262] 'process raft request' (duration: 121.198409ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.278199Z","caller":"traceutil/trace.go:171","msg":"trace[1855758620] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"126.929452ms","start":"2026-04-28T06:06:46.151259Z","end":"2026-04-28T06:06:46.278189Z","steps":["trace[1855758620] 'process raft request' (duration: 117.882067ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:46.278454Z","caller":"traceutil/trace.go:171","msg":"trace[1397169466] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"126.117512ms","start":"2026-04-28T06:06:46.152326Z","end":"2026-04-28T06:06:46.278443Z","steps":["trace[1397169466] 'process raft request' (duration: 125.334953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:47.120118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.466956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-28T06:06:48.151698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.654333ms","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-04-28T06:06:48.151838Z","caller":"traceutil/trace.go:171","msg":"trace[382405142] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1224; }","duration":"314.829997ms","start":"2026-04-28T06:06:47.836965Z","end":"2026-04-28T06:06:48.151795Z","steps":["trace[382405142] 'range keys from in-memory index tree' (duration: 314.514849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.151885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:47.836947Z","time spent":"314.926239ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:06:48.162686Z","caller":"traceutil/trace.go:171","msg":"trace[944630370] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"181.443056ms","start":"2026-04-28T06:06:47.981222Z","end":"2026-04-28T06:06:48.162665Z","steps":["trace[944630370] 'process raft request' (duration: 181.317993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:48.628042Z","caller":"traceutil/trace.go:171","msg":"trace[212695379] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"421.960098ms","start":"2026-04-28T06:06:48.206062Z","end":"2026-04-28T06:06:48.628022Z","steps":["trace[212695379] 'read index received' (duration: 421.870226ms)","trace[212695379] 'applied index is now lower than readState.Index' (duration: 89.052µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:48.628306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.227264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:06:48.62836Z","caller":"traceutil/trace.go:171","msg":"trace[149585293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"422.329607ms","start":"2026-04-28T06:06:48.206018Z","end":"2026-04-28T06:06:48.628348Z","steps":["trace[149585293] 'agreement among raft nodes before linearized reading' (duration: 422.141662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.62839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.205998Z","time spent":"422.387289ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:06:48.628552Z","caller":"traceutil/trace.go:171","msg":"trace[1329579992] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"471.157779ms","start":"2026-04-28T06:06:48.157188Z","end":"2026-04-28T06:06:48.628345Z","steps":["trace[1329579992] 'process raft request' (duration: 470.713838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.629582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.157158Z","time spent":"471.62029ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:48.643584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.104082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:48.64364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.999731ms","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-04-28T06:06:48.643664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.202336ms","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-04-28T06:06:48.643709Z","caller":"traceutil/trace.go:171","msg":"trace[333601972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"437.101983ms","start":"2026-04-28T06:06:48.206591Z","end":"2026-04-28T06:06:48.643693Z","steps":["trace[333601972] 'agreement among raft nodes before linearized reading' (duration: 436.853557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.643659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.276608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T06:06:48.643743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.206582Z","time spent":"437.154725ms","remote":"127.0.0.1:57102","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":"info","ts":"2026-04-28T06:06:48.643743Z","caller":"traceutil/trace.go:171","msg":"trace[1066826006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"437.305089ms","start":"2026-04-28T06:06:48.206418Z","end":"2026-04-28T06:06:48.643723Z","steps":["trace[1066826006] 'agreement among raft nodes before linearized reading' (duration: 437.087444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.643723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.094219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:48.643784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.206409Z","time spent":"437.36477ms","remote":"127.0.0.1:57102","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-04-28T06:06:48.643659Z","caller":"traceutil/trace.go:171","msg":"trace[885883508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"362.219635ms","start":"2026-04-28T06:06:48.281426Z","end":"2026-04-28T06:06:48.643646Z","steps":["trace[885883508] 'agreement among raft nodes before linearized reading' (duration: 362.113623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.643882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.281408Z","time spent":"362.464571ms","remote":"127.0.0.1:56860","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-04-28T06:06:48.643773Z","caller":"traceutil/trace.go:171","msg":"trace[576212456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"437.431402ms","start":"2026-04-28T06:06:48.206329Z","end":"2026-04-28T06:06:48.64376Z","steps":["trace[576212456] 'agreement among raft nodes before linearized reading' (duration: 437.161765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.644091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.206306Z","time spent":"437.74158ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:06:48.643817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.434672ms","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-04-28T06:06:48.644245Z","caller":"traceutil/trace.go:171","msg":"trace[1332529937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"437.869863ms","start":"2026-04-28T06:06:48.206334Z","end":"2026-04-28T06:06:48.644204Z","steps":["trace[1332529937] 'agreement among raft nodes before linearized reading' (duration: 437.140585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.644286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:48.206315Z","time spent":"437.958336ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:06:48.643811Z","caller":"traceutil/trace.go:171","msg":"trace[758797956] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1227; }","duration":"256.319885ms","start":"2026-04-28T06:06:48.387471Z","end":"2026-04-28T06:06:48.643791Z","steps":["trace[758797956] 'agreement among raft nodes before linearized reading' (duration: 256.072939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:48.887721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.748314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:48.887813Z","caller":"traceutil/trace.go:171","msg":"trace[523721971] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"204.57923ms","start":"2026-04-28T06:06:48.683217Z","end":"2026-04-28T06:06:48.887797Z","steps":["trace[523721971] 'process raft request' (duration: 55.687632ms)","trace[523721971] 'compare' (duration: 148.650382ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:01.46726Z","caller":"traceutil/trace.go:171","msg":"trace[446514363] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"126.682126ms","start":"2026-04-28T06:07:01.340555Z","end":"2026-04-28T06:07:01.467237Z","steps":["trace[446514363] 'read index received' (duration: 126.42897ms)","trace[446514363] 'applied index is now lower than readState.Index' (duration: 251.966µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:01.467384Z","caller":"traceutil/trace.go:171","msg":"trace[1543170808] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"182.540413ms","start":"2026-04-28T06:07:01.284809Z","end":"2026-04-28T06:07:01.467349Z","steps":["trace[1543170808] 'process raft request' (duration: 182.278046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:01.467454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.88481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:07:01.467482Z","caller":"traceutil/trace.go:171","msg":"trace[1367407866] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1319; }","duration":"126.967873ms","start":"2026-04-28T06:07:01.340506Z","end":"2026-04-28T06:07:01.467473Z","steps":["trace[1367407866] 'agreement among raft nodes before linearized reading' (duration: 126.817349ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:23.252387Z","caller":"traceutil/trace.go:171","msg":"trace[1766661602] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"210.995505ms","start":"2026-04-28T06:08:23.041363Z","end":"2026-04-28T06:08:23.252358Z","steps":["trace[1766661602] 'process raft request' (duration: 210.589336ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:23.252359Z","caller":"traceutil/trace.go:171","msg":"trace[1282597504] linearizableReadLoop","detail":"{readStateIndex:1762; appliedIndex:1761; }","duration":"135.088406ms","start":"2026-04-28T06:08:23.117239Z","end":"2026-04-28T06:08:23.252327Z","steps":["trace[1282597504] 'read index received' (duration: 134.622694ms)","trace[1282597504] 'applied index is now lower than readState.Index' (duration: 464.522µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:23.252803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.792584ms","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-04-28T06:08:23.252842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.947748ms","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-04-28T06:08:23.252862Z","caller":"traceutil/trace.go:171","msg":"trace[1510686764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1691; }","duration":"133.884416ms","start":"2026-04-28T06:08:23.118964Z","end":"2026-04-28T06:08:23.252848Z","steps":["trace[1510686764] 'agreement among raft nodes before linearized reading' (duration: 133.703002ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:23.252877Z","caller":"traceutil/trace.go:171","msg":"trace[1329174933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1691; }","duration":"108.01296ms","start":"2026-04-28T06:08:23.144854Z","end":"2026-04-28T06:08:23.252867Z","steps":["trace[1329174933] 'agreement among raft nodes before linearized reading' (duration: 107.902247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:23.253011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.239436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T06:08:23.253049Z","caller":"traceutil/trace.go:171","msg":"trace[773396077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1691; }","duration":"130.295717ms","start":"2026-04-28T06:08:23.122744Z","end":"2026-04-28T06:08:23.25304Z","steps":["trace[773396077] 'agreement among raft nodes before linearized reading' (duration: 130.203845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:23.253077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.024181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:08:23.253115Z","caller":"traceutil/trace.go:171","msg":"trace[2017674741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1691; }","duration":"106.098083ms","start":"2026-04-28T06:08:23.147005Z","end":"2026-04-28T06:08:23.253103Z","steps":["trace[2017674741] 'agreement among raft nodes before linearized reading' (duration: 106.00978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:23.257811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.54293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T06:08:23.2579Z","caller":"traceutil/trace.go:171","msg":"trace[2116889708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1691; }","duration":"140.665403ms","start":"2026-04-28T06:08:23.117212Z","end":"2026-04-28T06:08:23.257878Z","steps":["trace[2116889708] 'agreement among raft nodes before linearized reading' (duration: 135.419613ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:50.755887Z","caller":"traceutil/trace.go:171","msg":"trace[2059129866] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"182.138965ms","start":"2026-04-28T06:08:50.573716Z","end":"2026-04-28T06:08:50.755855Z","steps":["trace[2059129866] 'process raft request' (duration: 181.988131ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:31.067631Z","caller":"traceutil/trace.go:171","msg":"trace[2115525664] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"701.419885ms","start":"2026-04-28T06:09:30.366189Z","end":"2026-04-28T06:09:31.067609Z","steps":["trace[2115525664] 'process raft request' (duration: 701.277362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.067806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:30.36616Z","time spent":"701.576829ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:31.693652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123201452218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:09:32.194594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123201452218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:09:32.339638Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27228459s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:09:32.343055Z","caller":"traceutil/trace.go:171","msg":"trace[1922608208] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2086; }","duration":"1.14971961s","start":"2026-04-28T06:09:31.193293Z","end":"2026-04-28T06:09:32.343012Z","steps":["trace[1922608208] 'read index received' (duration: 1.14647922s)","trace[1922608208] 'applied index is now lower than readState.Index' (duration: 3.23944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:32.343048Z","caller":"traceutil/trace.go:171","msg":"trace[1211226787] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"1.452991271s","start":"2026-04-28T06:09:30.89003Z","end":"2026-04-28T06:09:32.343022Z","steps":["trace[1211226787] 'process raft request' (duration: 1.449744261s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.343194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062963538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:32.343928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:30.89001Z","time spent":"1.453102113s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:32.344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.422757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:09:32.344026Z","caller":"traceutil/trace.go:171","msg":"trace[109034756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2003; }","duration":"750.467249ms","start":"2026-04-28T06:09:31.593551Z","end":"2026-04-28T06:09:32.344018Z","steps":["trace[109034756] 'agreement among raft nodes before linearized reading' (duration: 750.364767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.52855ms","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-04-28T06:09:32.344049Z","caller":"traceutil/trace.go:171","msg":"trace[1138409399] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2003; }","duration":"750.571912ms","start":"2026-04-28T06:09:31.593472Z","end":"2026-04-28T06:09:32.344044Z","steps":["trace[1138409399] 'agreement among raft nodes before linearized reading' (duration: 750.484739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.593534Z","time spent":"750.50934ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:32.344068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.593461Z","time spent":"750.602652ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-28T06:09:32.34394Z","caller":"traceutil/trace.go:171","msg":"trace[1438275203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2003; }","duration":"1.063749607s","start":"2026-04-28T06:09:31.28018Z","end":"2026-04-28T06:09:32.343929Z","steps":["trace[1438275203] 'agreement among raft nodes before linearized reading' (duration: 1.062981038s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.453185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:32.344113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.28016Z","time spent":"1.063943081s","remote":"127.0.0.1:56860","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-04-28T06:09:32.344131Z","caller":"traceutil/trace.go:171","msg":"trace[715492958] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2003; }","duration":"222.538087ms","start":"2026-04-28T06:09:32.121582Z","end":"2026-04-28T06:09:32.34412Z","steps":["trace[715492958] 'agreement among raft nodes before linearized reading' (duration: 222.472806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.34415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.073013ms","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-04-28T06:09:32.344167Z","caller":"traceutil/trace.go:171","msg":"trace[1892076930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2003; }","duration":"751.089063ms","start":"2026-04-28T06:09:31.593073Z","end":"2026-04-28T06:09:32.344162Z","steps":["trace[1892076930] 'agreement among raft nodes before linearized reading' (duration: 751.041122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.593059Z","time spent":"751.120565ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:32.344209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.119014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-04-28T06:09:32.343196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149896834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:32.344231Z","caller":"traceutil/trace.go:171","msg":"trace[401026040] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2003; }","duration":"565.175236ms","start":"2026-04-28T06:09:31.779047Z","end":"2026-04-28T06:09:32.344222Z","steps":["trace[401026040] 'agreement among raft nodes before linearized reading' (duration: 565.122695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.779027Z","time spent":"565.220497ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:09:32.344251Z","caller":"traceutil/trace.go:171","msg":"trace[1736420199] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2003; }","duration":"1.15099853s","start":"2026-04-28T06:09:31.193238Z","end":"2026-04-28T06:09:32.344237Z","steps":["trace[1736420199] 'agreement among raft nodes before linearized reading' (duration: 1.149905384s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.314973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:09:32.34432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.196114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:32.344327Z","caller":"traceutil/trace.go:171","msg":"trace[1129072285] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2003; }","duration":"218.377985ms","start":"2026-04-28T06:09:32.125925Z","end":"2026-04-28T06:09:32.344303Z","steps":["trace[1129072285] 'agreement among raft nodes before linearized reading' (duration: 218.337384ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:32.344338Z","caller":"traceutil/trace.go:171","msg":"trace[554147997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2003; }","duration":"757.241115ms","start":"2026-04-28T06:09:31.587092Z","end":"2026-04-28T06:09:32.344333Z","steps":["trace[554147997] 'agreement among raft nodes before linearized reading' (duration: 757.185783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.58708Z","time spent":"757.272296ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:09:32.344361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.452624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:09:32.344377Z","caller":"traceutil/trace.go:171","msg":"trace[400107942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2003; }","duration":"747.487145ms","start":"2026-04-28T06:09:31.596885Z","end":"2026-04-28T06:09:32.344372Z","steps":["trace[400107942] 'agreement among raft nodes before linearized reading' (duration: 747.438783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.344394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.596875Z","time spent":"747.515205ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:32.344287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.193217Z","time spent":"1.151058491s","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:32.345063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.1093ms","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-04-28T06:09:32.345089Z","caller":"traceutil/trace.go:171","msg":"trace[327397442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2003; }","duration":"763.179562ms","start":"2026-04-28T06:09:31.581903Z","end":"2026-04-28T06:09:32.345082Z","steps":["trace[327397442] 'agreement among raft nodes before linearized reading' (duration: 762.516875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.345108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.581879Z","time spent":"763.224463ms","remote":"127.0.0.1:57102","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-04-28T06:09:32.345291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.579756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:09:32.34531Z","caller":"traceutil/trace.go:171","msg":"trace[369003508] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:2003; }","duration":"996.630777ms","start":"2026-04-28T06:09:31.348673Z","end":"2026-04-28T06:09:32.345304Z","steps":["trace[369003508] 'agreement among raft nodes before linearized reading' (duration: 996.575356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.345334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.348658Z","time spent":"996.672198ms","remote":"127.0.0.1:56872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-04-28T06:09:35.394203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366246619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:35.394294Z","caller":"traceutil/trace.go:171","msg":"trace[915429611] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2011; }","duration":"1.366394013s","start":"2026-04-28T06:09:34.027881Z","end":"2026-04-28T06:09:35.394275Z","steps":["trace[915429611] 'count revisions from in-memory index tree' (duration: 1.366166717s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.394355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.027861Z","time spent":"1.366482864s","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:35.394537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116499722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:35.39458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.600465993s","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:526"} {"level":"info","ts":"2026-04-28T06:09:35.394625Z","caller":"traceutil/trace.go:171","msg":"trace[2071083680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"1.116595504s","start":"2026-04-28T06:09:34.27801Z","end":"2026-04-28T06:09:35.394605Z","steps":["trace[2071083680] 'range keys from in-memory index tree' (duration: 1.11642927s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:35.394652Z","caller":"traceutil/trace.go:171","msg":"trace[216250640] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2011; }","duration":"2.600578605s","start":"2026-04-28T06:09:32.794051Z","end":"2026-04-28T06:09:35.39463Z","steps":["trace[216250640] 'range keys from in-memory index tree' (duration: 2.600290809s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.394667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094676086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-28T06:09:35.394702Z","caller":"traceutil/trace.go:171","msg":"trace[1063134666] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2011; }","duration":"1.094730968s","start":"2026-04-28T06:09:34.299961Z","end":"2026-04-28T06:09:35.394692Z","steps":["trace[1063134666] 'range keys from in-memory index tree' (duration: 1.094559984s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.394709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:32.794032Z","time spent":"2.600652598s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:09:35.394734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.299952Z","time spent":"1.094776029s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:09:35.394668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.277951Z","time spent":"1.116707467s","remote":"127.0.0.1:56860","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-04-28T06:09:35.395477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.171384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:35.395661Z","caller":"traceutil/trace.go:171","msg":"trace[1419646103] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2096; }","duration":"1.035327549s","start":"2026-04-28T06:09:34.360313Z","end":"2026-04-28T06:09:35.39564Z","steps":["trace[1419646103] 'read index received' (duration: 36.849346ms)","trace[1419646103] 'applied index is now lower than readState.Index' (duration: 998.476293ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:35.395961Z","caller":"traceutil/trace.go:171","msg":"trace[1149638314] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"1.045931139s","start":"2026-04-28T06:09:34.350006Z","end":"2026-04-28T06:09:35.395937Z","steps":["trace[1149638314] 'process raft request' (duration: 47.19849ms)","trace[1149638314] 'compare' (duration: 997.758764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:35.396058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.349988Z","time spent":"1.046019811s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:35.39625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035933223s","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-04-28T06:09:35.396294Z","caller":"traceutil/trace.go:171","msg":"trace[1514784295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2012; }","duration":"1.036049886s","start":"2026-04-28T06:09:34.360231Z","end":"2026-04-28T06:09:35.396281Z","steps":["trace[1514784295] 'agreement among raft nodes before linearized reading' (duration: 1.035923913s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.396331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.360207Z","time spent":"1.036114197s","remote":"127.0.0.1:57102","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-04-28T06:09:35.396419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036022905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:35.396537Z","caller":"traceutil/trace.go:171","msg":"trace[887065081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2012; }","duration":"1.036136378s","start":"2026-04-28T06:09:34.36036Z","end":"2026-04-28T06:09:35.396497Z","steps":["trace[887065081] 'agreement among raft nodes before linearized reading' (duration: 1.035926692s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.396569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.360351Z","time spent":"1.03620961s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:09:35.396686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.718597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:09:35.39671Z","caller":"traceutil/trace.go:171","msg":"trace[47089063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"989.763369ms","start":"2026-04-28T06:09:34.406939Z","end":"2026-04-28T06:09:35.396702Z","steps":["trace[47089063] 'agreement among raft nodes before linearized reading' (duration: 989.691977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.396737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.406929Z","time spent":"989.803629ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:35.396794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03421879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-28T06:09:35.3968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0362209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:09:35.39682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033786561s","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-04-28T06:09:35.396838Z","caller":"traceutil/trace.go:171","msg":"trace[933063302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2012; }","duration":"1.034373965s","start":"2026-04-28T06:09:34.362452Z","end":"2026-04-28T06:09:35.396826Z","steps":["trace[933063302] 'agreement among raft nodes before linearized reading' (duration: 1.034255692s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:35.396849Z","caller":"traceutil/trace.go:171","msg":"trace[699061453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2012; }","duration":"1.036299742s","start":"2026-04-28T06:09:34.360535Z","end":"2026-04-28T06:09:35.396834Z","steps":["trace[699061453] 'agreement among raft nodes before linearized reading' (duration: 1.036178459s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.396874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.36244Z","time spent":"1.034427106s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:09:35.396878Z","caller":"traceutil/trace.go:171","msg":"trace[590241713] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2012; }","duration":"1.033885652s","start":"2026-04-28T06:09:34.362975Z","end":"2026-04-28T06:09:35.396861Z","steps":["trace[590241713] 'agreement among raft nodes before linearized reading' (duration: 1.033712548s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.396893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.360529Z","time spent":"1.036352803s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:35.396922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.362965Z","time spent":"1.033945514s","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-28T06:09:35.397035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012205839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:35.397078Z","caller":"traceutil/trace.go:171","msg":"trace[137381646] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2012; }","duration":"1.012276911s","start":"2026-04-28T06:09:34.384789Z","end":"2026-04-28T06:09:35.397066Z","steps":["trace[137381646] 'agreement among raft nodes before linearized reading' (duration: 1.012193109s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:35.397122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:34.384777Z","time spent":"1.012331942s","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:37.323321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.256493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T06:09:37.323426Z","caller":"traceutil/trace.go:171","msg":"trace[1792635752] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2107; }","duration":"199.866861ms","start":"2026-04-28T06:09:37.123542Z","end":"2026-04-28T06:09:37.323408Z","steps":["trace[1792635752] 'read index received' (duration: 38.4µs)","trace[1792635752] 'applied index is now lower than readState.Index' (duration: 199.826991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:37.323826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.266611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T06:09:37.323869Z","caller":"traceutil/trace.go:171","msg":"trace[687134989] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2022; }","duration":"200.320873ms","start":"2026-04-28T06:09:37.123537Z","end":"2026-04-28T06:09:37.323858Z","steps":["trace[687134989] 'agreement among raft nodes before linearized reading' (duration: 200.147379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:07.982163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.872904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:10:07.982287Z","caller":"traceutil/trace.go:171","msg":"trace[1108011385] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"162.529695ms","start":"2026-04-28T06:10:07.819737Z","end":"2026-04-28T06:10:07.982267Z","steps":["trace[1108011385] 'process raft request' (duration: 20.462497ms)","trace[1108011385] 'compare' (duration: 141.752621ms)"],"step_count":2}