{"level":"warn","ts":"2026-05-07T00:46:35.03898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T00:46:35.039062Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T00:46:35.039118Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T00:46:35.039131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-07T00:46:35.039149Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T00:46:35.039729Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-05-07T00:46:35.039802Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T00:46:35.043627Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.621948ms"} {"level":"info","ts":"2026-05-07T00:46:35.052203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-05-07T00:46:35.052302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T00:46:35.052327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-05-07T00:46:35.052343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T00:46:35.052355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-05-07T00:46:35.052409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-05-07T00:46:35.058718Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T00:46:35.060347Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T00:46:35.061668Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T00:46:35.06385Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T00:46:35.064335Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T00:46:35.066047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T00:46:35.066359Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T00:46:35.066397Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T00:46:35.066715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-05-07T00:46:35.068935Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-07T00:46:35.070501Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T00:46:35.07078Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T00:46:35.070826Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T00:46:35.071238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-07T00:46:35.071263Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-07T00:46:36.053033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T00:46:36.0531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T00:46:36.05314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-05-07T00:46:36.053158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-05-07T00:46:36.053169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-07T00:46:36.053188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-05-07T00:46:36.053208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-07T00:46:36.054071Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T00:46:36.054099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T00:46:36.054153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T00:46:36.054167Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:46:36.054455Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T00:46:36.054528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T00:46:36.055101Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:46:36.05526Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:46:36.055298Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T00:46:36.056235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-05-07T00:46:36.057272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T00:46:37.464397Z","caller":"traceutil/trace.go:171","msg":"trace[1108376129] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"106.026851ms","start":"2026-05-07T00:46:37.358351Z","end":"2026-05-07T00:46:37.464378Z","steps":["trace[1108376129] 'process raft request' (duration: 24.595998ms)","trace[1108376129] 'compare' (duration: 81.34399ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:46:37.466148Z","caller":"traceutil/trace.go:171","msg":"trace[345087829] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"107.373536ms","start":"2026-05-07T00:46:37.358751Z","end":"2026-05-07T00:46:37.466125Z","steps":["trace[345087829] 'process raft request' (duration: 107.265524ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466287Z","caller":"traceutil/trace.go:171","msg":"trace[1923589042] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"107.48028ms","start":"2026-05-07T00:46:37.358793Z","end":"2026-05-07T00:46:37.466273Z","steps":["trace[1923589042] 'process raft request' (duration: 107.263034ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466345Z","caller":"traceutil/trace.go:171","msg":"trace[2023705017] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"106.396081ms","start":"2026-05-07T00:46:37.359937Z","end":"2026-05-07T00:46:37.466333Z","steps":["trace[2023705017] 'process raft request' (duration: 106.170305ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466466Z","caller":"traceutil/trace.go:171","msg":"trace[1042338419] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"106.414361ms","start":"2026-05-07T00:46:37.360045Z","end":"2026-05-07T00:46:37.466459Z","steps":["trace[1042338419] 'process raft request' (duration: 106.081243ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466462Z","caller":"traceutil/trace.go:171","msg":"trace[1292004403] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"107.573122ms","start":"2026-05-07T00:46:37.358878Z","end":"2026-05-07T00:46:37.466451Z","steps":["trace[1292004403] 'process raft request' (duration: 107.198022ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466468Z","caller":"traceutil/trace.go:171","msg":"trace[542763511] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"108.000634ms","start":"2026-05-07T00:46:37.358447Z","end":"2026-05-07T00:46:37.466448Z","steps":["trace[542763511] 'process raft request' (duration: 107.528861ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.466182Z","caller":"traceutil/trace.go:171","msg":"trace[208574182] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"107.11795ms","start":"2026-05-07T00:46:37.35904Z","end":"2026-05-07T00:46:37.466158Z","steps":["trace[208574182] 'process raft request' (duration: 107.052748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.935438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.707293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-07T00:46:37.935641Z","caller":"traceutil/trace.go:171","msg":"trace[1446966801] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"383.093925ms","start":"2026-05-07T00:46:37.552514Z","end":"2026-05-07T00:46:37.935608Z","steps":["trace[1446966801] 'process raft request' (duration: 64.702164ms)","trace[1446966801] 'compare' (duration: 317.454196ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:46:37.935908Z","caller":"traceutil/trace.go:171","msg":"trace[20249400] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"383.24398ms","start":"2026-05-07T00:46:37.552629Z","end":"2026-05-07T00:46:37.935873Z","steps":["trace[20249400] 'process raft request' (duration: 382.934971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.936092Z","caller":"traceutil/trace.go:171","msg":"trace[32707070] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"383.149886ms","start":"2026-05-07T00:46:37.552919Z","end":"2026-05-07T00:46:37.936068Z","steps":["trace[32707070] 'process raft request' (duration: 383.064795ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.936121Z","caller":"traceutil/trace.go:171","msg":"trace[495388018] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"383.343512ms","start":"2026-05-07T00:46:37.552766Z","end":"2026-05-07T00:46:37.936109Z","steps":["trace[495388018] 'process raft request' (duration: 383.163597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.936199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552903Z","time spent":"383.245589ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:37.936321Z","caller":"traceutil/trace.go:171","msg":"trace[603938518] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"383.462136ms","start":"2026-05-07T00:46:37.552852Z","end":"2026-05-07T00:46:37.936314Z","steps":["trace[603938518] 'process raft request' (duration: 383.113676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.936351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552842Z","time spent":"383.496216ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:37.936358Z","caller":"traceutil/trace.go:171","msg":"trace[1311627902] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"383.150967ms","start":"2026-05-07T00:46:37.553198Z","end":"2026-05-07T00:46:37.936349Z","steps":["trace[1311627902] 'process raft request' (duration: 382.805708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.936456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.553189Z","time spent":"383.208738ms","remote":"127.0.0.1:57340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:46:37.936353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552742Z","time spent":"383.413764ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:37.93611Z","caller":"traceutil/trace.go:171","msg":"trace[2058942484] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"372.482351ms","start":"2026-05-07T00:46:37.563612Z","end":"2026-05-07T00:46:37.936094Z","steps":["trace[2058942484] 'process raft request' (duration: 372.426939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.936447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552619Z","time spent":"383.498286ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:37.936642Z","caller":"traceutil/trace.go:171","msg":"trace[611541408] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:27; }","duration":"374.554927ms","start":"2026-05-07T00:46:37.562042Z","end":"2026-05-07T00:46:37.936597Z","steps":["trace[611541408] 'read index received' (duration: 55.300342ms)","trace[611541408] 'applied index is now lower than readState.Index' (duration: 319.252735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:46:37.936693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.563595Z","time spent":"372.918112ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:46:37.93602Z","caller":"traceutil/trace.go:171","msg":"trace[1277143063] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"383.382593ms","start":"2026-05-07T00:46:37.552624Z","end":"2026-05-07T00:46:37.936006Z","steps":["trace[1277143063] 'process raft request' (duration: 383.143417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.935907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552499Z","time spent":"383.236188ms","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:46:37.936935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.552608Z","time spent":"384.301237ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:46:37.936946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.834864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T00:46:37.93697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.870164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T00:46:37.936991Z","caller":"traceutil/trace.go:171","msg":"trace[447656818] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:31; }","duration":"318.930295ms","start":"2026-05-07T00:46:37.618055Z","end":"2026-05-07T00:46:37.936985Z","steps":["trace[447656818] 'agreement among raft nodes before linearized reading' (duration: 318.888135ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:37.936992Z","caller":"traceutil/trace.go:171","msg":"trace[951986658] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:31; }","duration":"374.959817ms","start":"2026-05-07T00:46:37.562017Z","end":"2026-05-07T00:46:37.936977Z","steps":["trace[951986658] 'agreement among raft nodes before linearized reading' (duration: 374.729281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:37.937014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.618036Z","time spent":"318.967687ms","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-07T00:46:37.937066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:37.562011Z","time spent":"375.006658ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"info","ts":"2026-05-07T00:46:40.576327Z","caller":"traceutil/trace.go:171","msg":"trace[1745211124] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:220; }","duration":"314.518358ms","start":"2026-05-07T00:46:40.261783Z","end":"2026-05-07T00:46:40.576302Z","steps":["trace[1745211124] 'read index received' (duration: 286.331893ms)","trace[1745211124] 'applied index is now lower than readState.Index' (duration: 28.185635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:46:40.576534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.739573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:46:40.576603Z","caller":"traceutil/trace.go:171","msg":"trace[1833623442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:216; }","duration":"314.853287ms","start":"2026-05-07T00:46:40.26174Z","end":"2026-05-07T00:46:40.576593Z","steps":["trace[1833623442] 'agreement among raft nodes before linearized reading' (duration: 314.717273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:40.576698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:40.261728Z","time spent":"314.96022ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T00:46:40.576588Z","caller":"traceutil/trace.go:171","msg":"trace[744497440] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"336.500767ms","start":"2026-05-07T00:46:40.240051Z","end":"2026-05-07T00:46:40.576551Z","steps":["trace[744497440] 'process raft request' (duration: 308.124696ms)","trace[744497440] 'compare' (duration: 28.010001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:46:40.576931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:40.240028Z","time spent":"336.764863ms","remote":"127.0.0.1:57600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T00:46:41.077062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.124108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:46:41.077386Z","caller":"traceutil/trace.go:171","msg":"trace[194515150] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:221; }","duration":"186.099306ms","start":"2026-05-07T00:46:40.891175Z","end":"2026-05-07T00:46:41.077274Z","steps":["trace[194515150] 'read index received' (duration: 14.94039ms)","trace[194515150] 'applied index is now lower than readState.Index' (duration: 171.157606ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:46:41.077476Z","caller":"traceutil/trace.go:171","msg":"trace[1696493267] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"496.879134ms","start":"2026-05-07T00:46:40.580565Z","end":"2026-05-07T00:46:41.077444Z","steps":["trace[1696493267] 'process raft request' (duration: 195.302063ms)","trace[1696493267] 'compare' (duration: 300.942554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:46:41.077526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.337682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T00:46:41.077583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:40.580546Z","time spent":"496.988437ms","remote":"127.0.0.1:57600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":526,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:41.077599Z","caller":"traceutil/trace.go:171","msg":"trace[427483751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:218; }","duration":"186.444776ms","start":"2026-05-07T00:46:40.891142Z","end":"2026-05-07T00:46:41.077587Z","steps":["trace[427483751] 'agreement among raft nodes before linearized reading' (duration: 186.291122ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:41.07775Z","caller":"traceutil/trace.go:171","msg":"trace[993534087] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"496.968545ms","start":"2026-05-07T00:46:40.580757Z","end":"2026-05-07T00:46:41.077725Z","steps":["trace[993534087] 'process raft request' (duration: 496.4101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:46:41.077912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:40.580736Z","time spent":"497.113239ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:46:41.19093Z","caller":"traceutil/trace.go:171","msg":"trace[1317077098] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"108.758314ms","start":"2026-05-07T00:46:41.082157Z","end":"2026-05-07T00:46:41.190915Z","steps":["trace[1317077098] 'process raft request' (duration: 108.323253ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:41.192673Z","caller":"traceutil/trace.go:171","msg":"trace[565078702] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"108.343243ms","start":"2026-05-07T00:46:41.084308Z","end":"2026-05-07T00:46:41.192651Z","steps":["trace[565078702] 'process raft request' (duration: 108.285392ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:46:41.192866Z","caller":"traceutil/trace.go:171","msg":"trace[2024141247] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"110.314925ms","start":"2026-05-07T00:46:41.082486Z","end":"2026-05-07T00:46:41.192801Z","steps":["trace[2024141247] 'process raft request' (duration: 110.022107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:05.387819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.706619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:7352"} {"level":"info","ts":"2026-05-07T00:47:05.388374Z","caller":"traceutil/trace.go:171","msg":"trace[1661213887] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"265.909992ms","start":"2026-05-07T00:47:05.12241Z","end":"2026-05-07T00:47:05.38832Z","steps":["trace[1661213887] 'process raft request' (duration: 265.641935ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.38842Z","caller":"traceutil/trace.go:171","msg":"trace[2058621251] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"265.714508ms","start":"2026-05-07T00:47:05.122625Z","end":"2026-05-07T00:47:05.38834Z","steps":["trace[2058621251] 'process raft request' (duration: 265.534103ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.388437Z","caller":"traceutil/trace.go:171","msg":"trace[315358969] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"265.668116ms","start":"2026-05-07T00:47:05.122748Z","end":"2026-05-07T00:47:05.388416Z","steps":["trace[315358969] 'process raft request' (duration: 265.477161ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.38862Z","caller":"traceutil/trace.go:171","msg":"trace[209167107] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"259.615465ms","start":"2026-05-07T00:47:05.12898Z","end":"2026-05-07T00:47:05.388595Z","steps":["trace[209167107] 'process raft request' (duration: 259.312666ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.388647Z","caller":"traceutil/trace.go:171","msg":"trace[1461699364] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"249.605454ms","start":"2026-05-07T00:47:05.139034Z","end":"2026-05-07T00:47:05.388639Z","steps":["trace[1461699364] 'process raft request' (duration: 249.385309ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.388647Z","caller":"traceutil/trace.go:171","msg":"trace[1826175668] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"254.050664ms","start":"2026-05-07T00:47:05.134584Z","end":"2026-05-07T00:47:05.388635Z","steps":["trace[1826175668] 'process raft request' (duration: 253.774018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.388614Z","caller":"traceutil/trace.go:171","msg":"trace[885434578] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"160.686644ms","start":"2026-05-07T00:47:05.227913Z","end":"2026-05-07T00:47:05.3886Z","steps":["trace[885434578] 'process raft request' (duration: 160.574271ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:05.388138Z","caller":"traceutil/trace.go:171","msg":"trace[1173297336] transaction","detail":"{read_only:false; number_of_response:1; response_revision:485; }","duration":"265.886241ms","start":"2026-05-07T00:47:05.122228Z","end":"2026-05-07T00:47:05.388114Z","steps":["trace[1173297336] 'process raft request' (duration: 155.774453ms)","trace[1173297336] 'compare' (duration: 109.615396ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:47:11.981808Z","caller":"traceutil/trace.go:171","msg":"trace[266884817] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"513.603527ms","start":"2026-05-07T00:47:11.467642Z","end":"2026-05-07T00:47:11.981245Z","steps":["trace[266884817] 'process raft request' (duration: 475.29854ms)","trace[266884817] 'compare' (duration: 33.277712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:47:11.981928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:11.467615Z","time spent":"514.254774ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:47:12.003272Z","caller":"traceutil/trace.go:171","msg":"trace[511685150] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"342.071282ms","start":"2026-05-07T00:47:11.66115Z","end":"2026-05-07T00:47:12.003221Z","steps":["trace[511685150] 'read index received' (duration: 281.654994ms)","trace[511685150] 'applied index is now lower than readState.Index' (duration: 60.415338ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:47:12.003366Z","caller":"traceutil/trace.go:171","msg":"trace[640865148] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"479.950665ms","start":"2026-05-07T00:47:11.523407Z","end":"2026-05-07T00:47:12.003357Z","steps":["trace[640865148] 'process raft request' (duration: 479.702658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.003448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:11.523383Z","time spent":"480.000737ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T00:47:12.003546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.515196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8nnzj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T00:47:12.003585Z","caller":"traceutil/trace.go:171","msg":"trace[1261690810] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8nnzj; range_end:; response_count:1; response_revision:578; }","duration":"298.593107ms","start":"2026-05-07T00:47:11.704981Z","end":"2026-05-07T00:47:12.003574Z","steps":["trace[1261690810] 'agreement among raft nodes before linearized reading' (duration: 298.446644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.003724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.601017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T00:47:12.003754Z","caller":"traceutil/trace.go:171","msg":"trace[1561334146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"342.666928ms","start":"2026-05-07T00:47:11.661077Z","end":"2026-05-07T00:47:12.003744Z","steps":["trace[1561334146] 'agreement among raft nodes before linearized reading' (duration: 342.591606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.003774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:11.661029Z","time spent":"342.74013ms","remote":"127.0.0.1:57490","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-05-07T00:47:12.582091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872564794634438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T00:47:12.590801Z","caller":"traceutil/trace.go:171","msg":"trace[440713493] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"550.282319ms","start":"2026-05-07T00:47:12.040496Z","end":"2026-05-07T00:47:12.590779Z","steps":["trace[440713493] 'process raft request' (duration: 542.743417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.5909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:12.040473Z","time spent":"550.383853ms","remote":"127.0.0.1:57458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:47:12.668332Z","caller":"traceutil/trace.go:171","msg":"trace[699806918] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"586.944501ms","start":"2026-05-07T00:47:12.081362Z","end":"2026-05-07T00:47:12.668307Z","steps":["trace[699806918] 'read index received' (duration: 501.857522ms)","trace[699806918] 'applied index is now lower than readState.Index' (duration: 85.086119ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:47:12.668398Z","caller":"traceutil/trace.go:171","msg":"trace[963046429] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"201.897667ms","start":"2026-05-07T00:47:12.466474Z","end":"2026-05-07T00:47:12.668371Z","steps":["trace[963046429] 'process raft request' (duration: 201.788694ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:47:12.668456Z","caller":"traceutil/trace.go:171","msg":"trace[2130101620] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"409.288783ms","start":"2026-05-07T00:47:12.259131Z","end":"2026-05-07T00:47:12.66842Z","steps":["trace[2130101620] 'process raft request' (duration: 409.011975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.668551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.181908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T00:47:12.66859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:12.259103Z","time spent":"409.416547ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T00:47:12.668594Z","caller":"traceutil/trace.go:171","msg":"trace[1185476002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"587.295191ms","start":"2026-05-07T00:47:12.081287Z","end":"2026-05-07T00:47:12.668583Z","steps":["trace[1185476002] 'agreement among raft nodes before linearized reading' (duration: 587.153367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:47:12.668632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:47:12.081269Z","time spent":"587.355233ms","remote":"127.0.0.1:57490","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":"info","ts":"2026-05-07T00:48:23.976154Z","caller":"traceutil/trace.go:171","msg":"trace[176914330] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"129.542971ms","start":"2026-05-07T00:48:23.846588Z","end":"2026-05-07T00:48:23.976131Z","steps":["trace[176914330] 'process raft request' (duration: 129.366525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:24.23532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.256786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:48:24.235416Z","caller":"traceutil/trace.go:171","msg":"trace[695098459] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"254.087637ms","start":"2026-05-07T00:48:23.981314Z","end":"2026-05-07T00:48:24.235401Z","steps":["trace[695098459] 'process raft request' (duration: 94.678107ms)","trace[695098459] 'compare' (duration: 159.153803ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:48:24.263718Z","caller":"traceutil/trace.go:171","msg":"trace[1311083761] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"207.000815ms","start":"2026-05-07T00:48:24.056692Z","end":"2026-05-07T00:48:24.263693Z","steps":["trace[1311083761] 'read index received' (duration: 19.304327ms)","trace[1311083761] 'applied index is now lower than readState.Index' (duration: 187.695578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:48:24.263896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.165649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:48:24.26395Z","caller":"traceutil/trace.go:171","msg":"trace[1883994794] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1028; }","duration":"207.298183ms","start":"2026-05-07T00:48:24.056641Z","end":"2026-05-07T00:48:24.263939Z","steps":["trace[1883994794] 'agreement among raft nodes before linearized reading' (duration: 207.17731ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:48:24.263803Z","caller":"traceutil/trace.go:171","msg":"trace[1317984567] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"249.100354ms","start":"2026-05-07T00:48:24.014672Z","end":"2026-05-07T00:48:24.263773Z","steps":["trace[1317984567] 'process raft request' (duration: 248.867366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:24.292162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.281175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:48:24.292242Z","caller":"traceutil/trace.go:171","msg":"trace[1024385770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"225.398418ms","start":"2026-05-07T00:48:24.066826Z","end":"2026-05-07T00:48:24.292225Z","steps":["trace[1024385770] 'agreement among raft nodes before linearized reading' (duration: 225.257745ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:48:25.100508Z","caller":"traceutil/trace.go:171","msg":"trace[106062853] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"112.70172ms","start":"2026-05-07T00:48:24.98778Z","end":"2026-05-07T00:48:25.100482Z","steps":["trace[106062853] 'read index received' (duration: 112.524475ms)","trace[106062853] 'applied index is now lower than readState.Index' (duration: 176.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:48:25.100565Z","caller":"traceutil/trace.go:171","msg":"trace[1094741869] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"116.293615ms","start":"2026-05-07T00:48:24.984235Z","end":"2026-05-07T00:48:25.100529Z","steps":["trace[1094741869] 'process raft request' (duration: 116.09531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:25.100638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.839463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:48:25.100675Z","caller":"traceutil/trace.go:171","msg":"trace[374302955] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1032; }","duration":"113.007208ms","start":"2026-05-07T00:48:24.987658Z","end":"2026-05-07T00:48:25.100665Z","steps":["trace[374302955] 'agreement among raft nodes before linearized reading' (duration: 112.921705ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:48:26.460541Z","caller":"traceutil/trace.go:171","msg":"trace[1851733247] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"101.377926ms","start":"2026-05-07T00:48:26.359143Z","end":"2026-05-07T00:48:26.460521Z","steps":["trace[1851733247] 'process raft request' (duration: 83.750773ms)","trace[1851733247] 'compare' (duration: 17.51487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:48:26.460785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.05855ms","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-05-07T00:48:26.460918Z","caller":"traceutil/trace.go:171","msg":"trace[681139700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"100.222275ms","start":"2026-05-07T00:48:26.360677Z","end":"2026-05-07T00:48:26.4609Z","steps":["trace[681139700] 'agreement among raft nodes before linearized reading' (duration: 99.948768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:56.865928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.265995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T00:48:56.866023Z","caller":"traceutil/trace.go:171","msg":"trace[1327663959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"169.412278ms","start":"2026-05-07T00:48:56.696593Z","end":"2026-05-07T00:48:56.866005Z","steps":["trace[1327663959] 'range keys from in-memory index tree' (duration: 169.105211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:56.866059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.443627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T00:48:56.866112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.185145ms","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-05-07T00:48:56.866156Z","caller":"traceutil/trace.go:171","msg":"trace[437661426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1225; }","duration":"173.59879ms","start":"2026-05-07T00:48:56.692539Z","end":"2026-05-07T00:48:56.866138Z","steps":["trace[437661426] 'range keys from in-memory index tree' (duration: 173.269152ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T00:48:56.866199Z","caller":"traceutil/trace.go:171","msg":"trace[69136958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1225; }","duration":"218.330858ms","start":"2026-05-07T00:48:56.647849Z","end":"2026-05-07T00:48:56.86618Z","steps":["trace[69136958] 'range keys from in-memory index tree' (duration: 217.947668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:58.570654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.036461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T00:48:58.570817Z","caller":"traceutil/trace.go:171","msg":"trace[1740622334] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"305.562426ms","start":"2026-05-07T00:48:58.265215Z","end":"2026-05-07T00:48:58.570778Z","steps":["trace[1740622334] 'process raft request' (duration: 165.304889ms)","trace[1740622334] 'compare' (duration: 139.782175ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T00:48:58.570884Z","caller":"traceutil/trace.go:171","msg":"trace[492214137] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"285.618512ms","start":"2026-05-07T00:48:58.28524Z","end":"2026-05-07T00:48:58.570858Z","steps":["trace[492214137] 'read index received' (duration: 145.268772ms)","trace[492214137] 'applied index is now lower than readState.Index' (duration: 140.34755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T00:48:58.571069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.804777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T00:48:58.571152Z","caller":"traceutil/trace.go:171","msg":"trace[1505520106] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1233; }","duration":"285.92974ms","start":"2026-05-07T00:48:58.285201Z","end":"2026-05-07T00:48:58.57113Z","steps":["trace[1505520106] 'agreement among raft nodes before linearized reading' (duration: 285.752905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T00:48:58.571193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:48:58.265193Z","time spent":"305.720651ms","remote":"127.0.0.1:57328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T00:49:08.120988Z","caller":"traceutil/trace.go:171","msg":"trace[1179060971] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"135.236308ms","start":"2026-05-07T00:49:07.985725Z","end":"2026-05-07T00:49:08.120961Z","steps":["trace[1179060971] 'process raft request' (duration: 135.023012ms)"],"step_count":1}