{"level":"warn","ts":"2026-04-07T00:06:52.359112Z","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-07T00:06:52.359225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-07T00:06:52.359293Z","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-07T00:06:52.359306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-07T00:06:52.359322Z","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-07T00:06:52.359942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-07T00:06:52.360015Z","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":16,"max-cpu-available":16,"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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-07T00:06:52.3638Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.579451ms"} {"level":"info","ts":"2026-04-07T00:06:52.369933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-07T00:06:52.369993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:06:52.370011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-07T00:06:52.370019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:06:52.370028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-07T00:06:52.370078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-07T00:06:52.374888Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:06:52.376507Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:06:52.377885Z","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-07T00:06:52.380722Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:06:52.383024Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:06:52.38377Z","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-07T00:06:52.383855Z","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-07T00:06:52.383874Z","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-07T00:06:52.384672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-07T00:06:52.384914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-07T00:06:52.386484Z","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-07T00:06:52.386593Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-07T00:06:52.386658Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-07T00:06:52.386735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:06:52.386773Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:06:52.670373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:06:52.670437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:06:52.670487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-07T00:06:52.670508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:06:52.670519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-07T00:06:52.670535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-07T00:06:52.670548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-07T00:06:52.671511Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:06:52.671631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:52.671802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:52.671778Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:52.671878Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:06:52.672303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:06:52.673852Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:52.674021Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:52.674061Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:52.677584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-07T00:06:52.679015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:07:20.358795Z","caller":"traceutil/trace.go:171","msg":"trace[54015726] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"252.537897ms","start":"2026-04-07T00:07:20.106231Z","end":"2026-04-07T00:07:20.358729Z","steps":["trace[54015726] 'read index received' (duration: 251.273825ms)","trace[54015726] 'applied index is now lower than readState.Index' (duration: 1.223921ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:20.358857Z","caller":"traceutil/trace.go:171","msg":"trace[2021024894] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"389.718456ms","start":"2026-04-07T00:07:19.969109Z","end":"2026-04-07T00:07:20.358827Z","steps":["trace[2021024894] 'process raft request' (duration: 388.66529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:20.358995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.751742ms","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-07T00:07:20.359052Z","caller":"traceutil/trace.go:171","msg":"trace[1355359098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"252.876426ms","start":"2026-04-07T00:07:20.106164Z","end":"2026-04-07T00:07:20.359041Z","steps":["trace[1355359098] 'agreement among raft nodes before linearized reading' (duration: 252.767243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:20.359382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.532676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-04-07T00:07:20.359388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.637829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-07T00:07:20.359483Z","caller":"traceutil/trace.go:171","msg":"trace[1263436843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:500; }","duration":"252.6776ms","start":"2026-04-07T00:07:20.106786Z","end":"2026-04-07T00:07:20.359463Z","steps":["trace[1263436843] 'agreement among raft nodes before linearized reading' (duration: 252.518397ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:20.359542Z","caller":"traceutil/trace.go:171","msg":"trace[1693635607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:500; }","duration":"252.851105ms","start":"2026-04-07T00:07:20.106664Z","end":"2026-04-07T00:07:20.359516Z","steps":["trace[1693635607] 'agreement among raft nodes before linearized reading' (duration: 252.566278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:20.359826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:19.96909Z","time spent":"389.83613ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:07:22.346002Z","caller":"traceutil/trace.go:171","msg":"trace[667504287] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"120.754823ms","start":"2026-04-07T00:07:22.225212Z","end":"2026-04-07T00:07:22.345967Z","steps":["trace[667504287] 'process raft request' (duration: 120.64668ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:22.346018Z","caller":"traceutil/trace.go:171","msg":"trace[1056181627] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"133.077825ms","start":"2026-04-07T00:07:22.212912Z","end":"2026-04-07T00:07:22.345989Z","steps":["trace[1056181627] 'process raft request' (duration: 132.88212ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:22.345998Z","caller":"traceutil/trace.go:171","msg":"trace[1212880993] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"145.434629ms","start":"2026-04-07T00:07:22.200525Z","end":"2026-04-07T00:07:22.345959Z","steps":["trace[1212880993] 'process raft request' (duration: 137.723563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:28.678951Z","caller":"traceutil/trace.go:171","msg":"trace[1506695395] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"167.954707ms","start":"2026-04-07T00:07:28.51097Z","end":"2026-04-07T00:07:28.678924Z","steps":["trace[1506695395] 'process raft request' (duration: 167.902555ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:28.678971Z","caller":"traceutil/trace.go:171","msg":"trace[653672569] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"260.674816ms","start":"2026-04-07T00:07:28.418221Z","end":"2026-04-07T00:07:28.678895Z","steps":["trace[653672569] 'process raft request' (duration: 229.878402ms)","trace[653672569] 'compare' (duration: 30.469845ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:28.679019Z","caller":"traceutil/trace.go:171","msg":"trace[1533384567] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"217.430993ms","start":"2026-04-07T00:07:28.461538Z","end":"2026-04-07T00:07:28.678969Z","steps":["trace[1533384567] 'process raft request' (duration: 217.266699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:28.679112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.586376ms","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-07T00:07:28.679176Z","caller":"traceutil/trace.go:171","msg":"trace[739945813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"259.70322ms","start":"2026-04-07T00:07:28.419456Z","end":"2026-04-07T00:07:28.679159Z","steps":["trace[739945813] 'agreement among raft nodes before linearized reading' (duration: 259.520274ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:28.678948Z","caller":"traceutil/trace.go:171","msg":"trace[376693317] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"224.647503ms","start":"2026-04-07T00:07:28.454264Z","end":"2026-04-07T00:07:28.678911Z","steps":["trace[376693317] 'read index received' (duration: 193.946332ms)","trace[376693317] 'applied index is now lower than readState.Index' (duration: 30.696441ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:29.317536Z","caller":"traceutil/trace.go:171","msg":"trace[457063438] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"550.660517ms","start":"2026-04-07T00:07:28.766844Z","end":"2026-04-07T00:07:29.317505Z","steps":["trace[457063438] 'process raft request' (duration: 528.703258ms)","trace[457063438] 'compare' (duration: 21.660122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:29.317715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:28.766822Z","time spent":"550.79494ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:07:29.318849Z","caller":"traceutil/trace.go:171","msg":"trace[22496655] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"220.757411ms","start":"2026-04-07T00:07:29.098058Z","end":"2026-04-07T00:07:29.318815Z","steps":["trace[22496655] 'process raft request' (duration: 219.663572ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:45.335751Z","caller":"traceutil/trace.go:171","msg":"trace[1254559548] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"194.542449ms","start":"2026-04-07T00:07:45.141165Z","end":"2026-04-07T00:07:45.335707Z","steps":["trace[1254559548] 'process raft request' (duration: 194.231341ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:35.431784Z","caller":"traceutil/trace.go:171","msg":"trace[1533223084] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"109.816532ms","start":"2026-04-07T00:08:35.321932Z","end":"2026-04-07T00:08:35.431749Z","steps":["trace[1533223084] 'read index received' (duration: 106.792161ms)","trace[1533223084] 'applied index is now lower than readState.Index' (duration: 3.022801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:35.431797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.106139ms","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-07T00:08:35.431918Z","caller":"traceutil/trace.go:171","msg":"trace[1470694000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1431; }","duration":"292.283282ms","start":"2026-04-07T00:08:35.139609Z","end":"2026-04-07T00:08:35.431892Z","steps":["trace[1470694000] 'range keys from in-memory index tree' (duration: 291.897712ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:35.431945Z","caller":"traceutil/trace.go:171","msg":"trace[1972129347] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"126.120187ms","start":"2026-04-07T00:08:35.305804Z","end":"2026-04-07T00:08:35.431924Z","steps":["trace[1972129347] 'process raft request' (duration: 122.941433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.432058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.101289ms","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-07T00:08:35.432114Z","caller":"traceutil/trace.go:171","msg":"trace[722407924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1432; }","duration":"110.209082ms","start":"2026-04-07T00:08:35.321891Z","end":"2026-04-07T00:08:35.4321Z","steps":["trace[722407924] 'agreement among raft nodes before linearized reading' (duration: 109.988976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:59.828789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.118528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:59.829045Z","caller":"traceutil/trace.go:171","msg":"trace[1428216564] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"165.513229ms","start":"2026-04-07T00:08:59.663516Z","end":"2026-04-07T00:08:59.82903Z","steps":["trace[1428216564] 'process raft request' (duration: 165.406176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:59.829014Z","caller":"traceutil/trace.go:171","msg":"trace[892326896] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"302.009413ms","start":"2026-04-07T00:08:59.52693Z","end":"2026-04-07T00:08:59.828939Z","steps":["trace[892326896] 'process raft request' (duration: 30.229387ms)","trace[892326896] 'compare' (duration: 270.81792ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:59.829047Z","caller":"traceutil/trace.go:171","msg":"trace[492234581] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"224.139724ms","start":"2026-04-07T00:08:59.60487Z","end":"2026-04-07T00:08:59.82901Z","steps":["trace[492234581] 'read index received' (duration: 50.532µs)","trace[492234581] 'applied index is now lower than readState.Index' (duration: 224.085252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:59.829251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:59.526903Z","time spent":"302.218689ms","remote":"127.0.0.1:42200","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-04-07T00:08:59.829358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.873926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:59.829461Z","caller":"traceutil/trace.go:171","msg":"trace[2120037491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1839; }","duration":"105.991379ms","start":"2026-04-07T00:08:59.723447Z","end":"2026-04-07T00:08:59.829438Z","steps":["trace[2120037491] 'agreement among raft nodes before linearized reading' (duration: 105.816495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:59.829482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.880797ms","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-07T00:08:59.829596Z","caller":"traceutil/trace.go:171","msg":"trace[1897254358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1839; }","duration":"224.094763ms","start":"2026-04-07T00:08:59.605469Z","end":"2026-04-07T00:08:59.829564Z","steps":["trace[1897254358] 'agreement among raft nodes before linearized reading' (duration: 223.752933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:59.829834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.974116ms","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-07T00:08:59.829868Z","caller":"traceutil/trace.go:171","msg":"trace[1401235299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1839; }","duration":"225.057388ms","start":"2026-04-07T00:08:59.604803Z","end":"2026-04-07T00:08:59.82986Z","steps":["trace[1401235299] 'agreement among raft nodes before linearized reading' (duration: 224.347919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:00.3411Z","caller":"traceutil/trace.go:171","msg":"trace[727973465] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"242.467563ms","start":"2026-04-07T00:09:00.098608Z","end":"2026-04-07T00:09:00.341075Z","steps":["trace[727973465] 'read index received' (duration: 242.437192ms)","trace[727973465] 'applied index is now lower than readState.Index' (duration: 29.341µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:00.34113Z","caller":"traceutil/trace.go:171","msg":"trace[115940427] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"260.818093ms","start":"2026-04-07T00:09:00.080285Z","end":"2026-04-07T00:09:00.341103Z","steps":["trace[115940427] 'process raft request' (duration: 260.520995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:00.341377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.75635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b6f8248-4d13-4b0d-879e-889e5dd4e873\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T00:09:00.34147Z","caller":"traceutil/trace.go:171","msg":"trace[463513192] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b6f8248-4d13-4b0d-879e-889e5dd4e873; range_end:; response_count:1; response_revision:1843; }","duration":"242.917095ms","start":"2026-04-07T00:09:00.09853Z","end":"2026-04-07T00:09:00.341447Z","steps":["trace[463513192] 'agreement among raft nodes before linearized reading' (duration: 242.631237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:00.424381Z","caller":"traceutil/trace.go:171","msg":"trace[1845487110] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"132.22862ms","start":"2026-04-07T00:09:00.292117Z","end":"2026-04-07T00:09:00.424345Z","steps":["trace[1845487110] 'process raft request' (duration: 131.792148ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:00.424404Z","caller":"traceutil/trace.go:171","msg":"trace[1075029284] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"113.654875ms","start":"2026-04-07T00:09:00.310709Z","end":"2026-04-07T00:09:00.424363Z","steps":["trace[1075029284] 'process raft request' (duration: 113.433249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:00.42473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.628602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b6f8248-4d13-4b0d-879e-889e5dd4e873\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T00:09:00.424815Z","caller":"traceutil/trace.go:171","msg":"trace[1047335236] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b6f8248-4d13-4b0d-879e-889e5dd4e873; range_end:; response_count:1; response_revision:1845; }","duration":"159.761366ms","start":"2026-04-07T00:09:00.265031Z","end":"2026-04-07T00:09:00.424793Z","steps":["trace[1047335236] 'agreement among raft nodes before linearized reading' (duration: 159.557571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:09.469713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.366683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:09:09.469889Z","caller":"traceutil/trace.go:171","msg":"trace[64251819] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"133.502534ms","start":"2026-04-07T00:09:09.336359Z","end":"2026-04-07T00:09:09.469861Z","steps":["trace[64251819] 'compare' (duration: 126.178428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:09.485626Z","caller":"traceutil/trace.go:171","msg":"trace[121916239] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"105.162669ms","start":"2026-04-07T00:09:09.380439Z","end":"2026-04-07T00:09:09.485602Z","steps":["trace[121916239] 'read index received' (duration: 105.013934ms)","trace[121916239] 'applied index is now lower than readState.Index' (duration: 147.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:09.485694Z","caller":"traceutil/trace.go:171","msg":"trace[890201954] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"139.009251ms","start":"2026-04-07T00:09:09.346659Z","end":"2026-04-07T00:09:09.485668Z","steps":["trace[890201954] 'process raft request' (duration: 138.806746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:09.485885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.433255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-07T00:09:09.485989Z","caller":"traceutil/trace.go:171","msg":"trace[1256375144] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1926; }","duration":"105.575138ms","start":"2026-04-07T00:09:09.380392Z","end":"2026-04-07T00:09:09.485967Z","steps":["trace[1256375144] 'agreement among raft nodes before linearized reading' (duration: 105.303831ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:12.780896Z","caller":"traceutil/trace.go:171","msg":"trace[652365227] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"142.563797ms","start":"2026-04-07T00:09:12.63831Z","end":"2026-04-07T00:09:12.780874Z","steps":["trace[652365227] 'read index received' (duration: 142.395873ms)","trace[652365227] 'applied index is now lower than readState.Index' (duration: 166.704µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:12.780958Z","caller":"traceutil/trace.go:171","msg":"trace[1257467348] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"170.373528ms","start":"2026-04-07T00:09:12.61055Z","end":"2026-04-07T00:09:12.780924Z","steps":["trace[1257467348] 'process raft request' (duration: 170.172813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:12.781064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.737271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:09:12.781103Z","caller":"traceutil/trace.go:171","msg":"trace[1121165739] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1948; }","duration":"142.812482ms","start":"2026-04-07T00:09:12.63828Z","end":"2026-04-07T00:09:12.781093Z","steps":["trace[1121165739] 'agreement among raft nodes before linearized reading' (duration: 142.72555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:12.781125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.834066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:09:12.781193Z","caller":"traceutil/trace.go:171","msg":"trace[1622904511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1948; }","duration":"102.939468ms","start":"2026-04-07T00:09:12.678236Z","end":"2026-04-07T00:09:12.781175Z","steps":["trace[1622904511] 'agreement among raft nodes before linearized reading' (duration: 102.833575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:13.276536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.632085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-07T00:09:13.276604Z","caller":"traceutil/trace.go:171","msg":"trace[1841720813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1948; }","duration":"406.740929ms","start":"2026-04-07T00:09:12.869847Z","end":"2026-04-07T00:09:13.276588Z","steps":["trace[1841720813] 'range keys from in-memory index tree' (duration: 406.378599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:13.276639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:12.869826Z","time spent":"406.80268ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12012,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T00:09:13.276793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.92583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T00:09:13.276975Z","caller":"traceutil/trace.go:171","msg":"trace[2071126479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1948; }","duration":"184.211877ms","start":"2026-04-07T00:09:13.092734Z","end":"2026-04-07T00:09:13.276946Z","steps":["trace[2071126479] 'range keys from in-memory index tree' (duration: 183.52248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:44.846383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.099158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:09:44.84649Z","caller":"traceutil/trace.go:171","msg":"trace[1521637475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2134; }","duration":"121.238491ms","start":"2026-04-07T00:09:44.725226Z","end":"2026-04-07T00:09:44.846465Z","steps":["trace[1521637475] 'range keys from in-memory index tree' (duration: 121.013336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:51.498794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.418859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:09:51.499007Z","caller":"traceutil/trace.go:171","msg":"trace[2146894805] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"598.171362ms","start":"2026-04-07T00:09:50.900794Z","end":"2026-04-07T00:09:51.498965Z","steps":["trace[2146894805] 'process raft request' (duration: 223.422904ms)","trace[2146894805] 'compare' (duration: 374.174593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:09:51.499233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:50.900778Z","time spent":"598.322615ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:09:51.499417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.366333ms","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-07T00:09:51.49949Z","caller":"traceutil/trace.go:171","msg":"trace[1396104339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"492.474406ms","start":"2026-04-07T00:09:51.006997Z","end":"2026-04-07T00:09:51.499471Z","steps":["trace[1396104339] 'agreement among raft nodes before linearized reading' (duration: 492.26575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:51.499522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:51.006976Z","time spent":"492.540267ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T00:09:51.49938Z","caller":"traceutil/trace.go:171","msg":"trace[1752143432] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"492.165417ms","start":"2026-04-07T00:09:51.007046Z","end":"2026-04-07T00:09:51.499212Z","steps":["trace[1752143432] 'read index received' (duration: 117.243865ms)","trace[1752143432] 'applied index is now lower than readState.Index' (duration: 374.887972ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:24.609427Z","caller":"traceutil/trace.go:171","msg":"trace[1142795769] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"136.610244ms","start":"2026-04-07T00:11:24.472792Z","end":"2026-04-07T00:11:24.609402Z","steps":["trace[1142795769] 'process raft request' (duration: 136.456ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:24.620976Z","caller":"traceutil/trace.go:171","msg":"trace[609475793] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"114.068218ms","start":"2026-04-07T00:11:24.506889Z","end":"2026-04-07T00:11:24.620957Z","steps":["trace[609475793] 'process raft request' (duration: 113.178753ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:39.989377Z","caller":"traceutil/trace.go:171","msg":"trace[1064066701] transaction","detail":"{read_only:false; response_revision:4086; number_of_response:1; }","duration":"209.931815ms","start":"2026-04-07T00:14:39.779413Z","end":"2026-04-07T00:14:39.989345Z","steps":["trace[1064066701] 'process raft request' (duration: 209.749841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:05.817189Z","caller":"traceutil/trace.go:171","msg":"trace[1420424826] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"113.868878ms","start":"2026-04-07T00:16:05.703276Z","end":"2026-04-07T00:16:05.817145Z","steps":["trace[1420424826] 'process raft request' (duration: 113.61229ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:05.965802Z","caller":"traceutil/trace.go:171","msg":"trace[1769583915] linearizableReadLoop","detail":"{readStateIndex:5352; appliedIndex:5351; }","duration":"101.271094ms","start":"2026-04-07T00:16:05.864507Z","end":"2026-04-07T00:16:05.965778Z","steps":["trace[1769583915] 'read index received' (duration: 63.848582ms)","trace[1769583915] 'applied index is now lower than readState.Index' (duration: 37.421432ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:05.965887Z","caller":"traceutil/trace.go:171","msg":"trace[2095378330] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"128.870606ms","start":"2026-04-07T00:16:05.836988Z","end":"2026-04-07T00:16:05.965859Z","steps":["trace[2095378330] 'process raft request' (duration: 91.382283ms)","trace[2095378330] 'compare' (duration: 37.299069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:05.96601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.48889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-07T00:16:05.966038Z","caller":"traceutil/trace.go:171","msg":"trace[2015029386] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5152; }","duration":"101.566502ms","start":"2026-04-07T00:16:05.864462Z","end":"2026-04-07T00:16:05.966028Z","steps":["trace[2015029386] 'agreement among raft nodes before linearized reading' (duration: 101.408598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:09.599065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.490388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h2mwl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:16:09.599161Z","caller":"traceutil/trace.go:171","msg":"trace[796055454] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h2mwl; range_end:; response_count:1; response_revision:5277; }","duration":"341.650172ms","start":"2026-04-07T00:16:09.257493Z","end":"2026-04-07T00:16:09.599143Z","steps":["trace[796055454] 'range keys from in-memory index tree' (duration: 341.24142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:09.599218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:09.257468Z","time spent":"341.725204ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-h2mwl\" "} {"level":"warn","ts":"2026-04-07T00:16:09.599519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.763721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:09.59958Z","caller":"traceutil/trace.go:171","msg":"trace[309660008] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5277; }","duration":"247.825602ms","start":"2026-04-07T00:16:09.351739Z","end":"2026-04-07T00:16:09.599564Z","steps":["trace[309660008] 'count revisions from in-memory index tree' (duration: 247.574436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:09.599647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.048953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719280"} {"level":"info","ts":"2026-04-07T00:16:09.599768Z","caller":"traceutil/trace.go:171","msg":"trace[890114879] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5277; }","duration":"143.224188ms","start":"2026-04-07T00:16:09.456516Z","end":"2026-04-07T00:16:09.59974Z","steps":["trace[890114879] 'range keys from in-memory index tree' (duration: 142.464117ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:09.729306Z","caller":"traceutil/trace.go:171","msg":"trace[1145737869] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"119.84389ms","start":"2026-04-07T00:16:09.609436Z","end":"2026-04-07T00:16:09.72928Z","steps":["trace[1145737869] 'process raft request' (duration: 119.676625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:11.728188Z","caller":"traceutil/trace.go:171","msg":"trace[324459492] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5519; }","duration":"115.995895ms","start":"2026-04-07T00:16:11.612173Z","end":"2026-04-07T00:16:11.728169Z","steps":["trace[324459492] 'read index received' (duration: 115.765979ms)","trace[324459492] 'applied index is now lower than readState.Index' (duration: 229.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:11.728273Z","caller":"traceutil/trace.go:171","msg":"trace[147163275] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"188.207916ms","start":"2026-04-07T00:16:11.540022Z","end":"2026-04-07T00:16:11.72823Z","steps":["trace[147163275] 'process raft request' (duration: 187.97567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:11.72837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.19333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h2mwl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:16:11.728407Z","caller":"traceutil/trace.go:171","msg":"trace[699551503] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h2mwl; range_end:; response_count:1; response_revision:5315; }","duration":"116.277103ms","start":"2026-04-07T00:16:11.61212Z","end":"2026-04-07T00:16:11.728397Z","steps":["trace[699551503] 'agreement among raft nodes before linearized reading' (duration: 116.153579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:11.943633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.539599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-07T00:16:11.94374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.044159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62911"} {"level":"info","ts":"2026-04-07T00:16:11.943817Z","caller":"traceutil/trace.go:171","msg":"trace[906871465] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5315; }","duration":"211.152492ms","start":"2026-04-07T00:16:11.732644Z","end":"2026-04-07T00:16:11.943797Z","steps":["trace[906871465] 'range keys from in-memory index tree' (duration: 210.657858ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:11.943914Z","caller":"traceutil/trace.go:171","msg":"trace[182408925] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5520; }","duration":"172.284512ms","start":"2026-04-07T00:16:11.771612Z","end":"2026-04-07T00:16:11.943897Z","steps":["trace[182408925] 'read index received' (duration: 51.432913ms)","trace[182408925] 'applied index is now lower than readState.Index' (duration: 120.850469ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:11.944032Z","caller":"traceutil/trace.go:171","msg":"trace[980967703] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"210.35354ms","start":"2026-04-07T00:16:11.73361Z","end":"2026-04-07T00:16:11.943964Z","steps":["trace[980967703] 'process raft request' (duration: 210.155784ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:11.944156Z","caller":"traceutil/trace.go:171","msg":"trace[1523098487] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"210.563776ms","start":"2026-04-07T00:16:11.733572Z","end":"2026-04-07T00:16:11.944136Z","steps":["trace[1523098487] 'process raft request' (duration: 89.42023ms)","trace[1523098487] 'compare' (duration: 120.413796ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:11.944165Z","caller":"traceutil/trace.go:171","msg":"trace[297322260] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"177.051782ms","start":"2026-04-07T00:16:11.767066Z","end":"2026-04-07T00:16:11.944118Z","steps":["trace[297322260] 'process raft request' (duration: 176.778384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:11.944761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.133344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-230c26db-7250-4431-9f8b-dadf37642c69\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-04-07T00:16:11.94484Z","caller":"traceutil/trace.go:171","msg":"trace[1052891312] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-230c26db-7250-4431-9f8b-dadf37642c69; range_end:; response_count:1; response_revision:5318; }","duration":"173.255478ms","start":"2026-04-07T00:16:11.771564Z","end":"2026-04-07T00:16:11.94482Z","steps":["trace[1052891312] 'agreement among raft nodes before linearized reading' (duration: 172.58372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:20.635643Z","caller":"traceutil/trace.go:171","msg":"trace[1978402340] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"101.256433ms","start":"2026-04-07T00:16:20.53435Z","end":"2026-04-07T00:16:20.635607Z","steps":["trace[1978402340] 'process raft request' (duration: 92.00242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:21.555897Z","caller":"traceutil/trace.go:171","msg":"trace[78627477] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"100.276198ms","start":"2026-04-07T00:16:21.455599Z","end":"2026-04-07T00:16:21.555875Z","steps":["trace[78627477] 'process raft request' (duration: 100.076302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:21.944074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.942615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:16:21.944361Z","caller":"traceutil/trace.go:171","msg":"trace[317842454] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"245.065528ms","start":"2026-04-07T00:16:21.699268Z","end":"2026-04-07T00:16:21.944334Z","steps":["trace[317842454] 'process raft request' (duration: 88.774052ms)","trace[317842454] 'compare' (duration: 155.822012ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:21.944405Z","caller":"traceutil/trace.go:171","msg":"trace[1785624000] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"243.988878ms","start":"2026-04-07T00:16:21.700381Z","end":"2026-04-07T00:16:21.94437Z","steps":["trace[1785624000] 'process raft request' (duration: 243.824163ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:21.944436Z","caller":"traceutil/trace.go:171","msg":"trace[91753356] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5712; }","duration":"185.626155ms","start":"2026-04-07T00:16:21.758786Z","end":"2026-04-07T00:16:21.944412Z","steps":["trace[91753356] 'read index received' (duration: 29.347081ms)","trace[91753356] 'applied index is now lower than readState.Index' (duration: 156.277364ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:21.944477Z","caller":"traceutil/trace.go:171","msg":"trace[926575596] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"243.999539ms","start":"2026-04-07T00:16:21.700464Z","end":"2026-04-07T00:16:21.944464Z","steps":["trace[926575596] 'process raft request' (duration: 243.830724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:21.944619Z","caller":"traceutil/trace.go:171","msg":"trace[831306236] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"243.397092ms","start":"2026-04-07T00:16:21.701205Z","end":"2026-04-07T00:16:21.944602Z","steps":["trace[831306236] 'process raft request' (duration: 243.150485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:21.944689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.908613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:21.944729Z","caller":"traceutil/trace.go:171","msg":"trace[1038424729] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5505; }","duration":"185.976736ms","start":"2026-04-07T00:16:21.758739Z","end":"2026-04-07T00:16:21.944716Z","steps":["trace[1038424729] 'agreement among raft nodes before linearized reading' (duration: 185.904803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:22.110919Z","caller":"traceutil/trace.go:171","msg":"trace[1320585890] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"102.322242ms","start":"2026-04-07T00:16:22.008566Z","end":"2026-04-07T00:16:22.110888Z","steps":["trace[1320585890] 'process raft request' (duration: 55.622837ms)","trace[1320585890] 'compare' (duration: 46.483739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:22.111114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.408127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h2mwl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:16:22.11117Z","caller":"traceutil/trace.go:171","msg":"trace[467246537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h2mwl; range_end:; response_count:1; response_revision:5508; }","duration":"123.514641ms","start":"2026-04-07T00:16:21.987642Z","end":"2026-04-07T00:16:22.111157Z","steps":["trace[467246537] 'agreement among raft nodes before linearized reading' (duration: 123.316545ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:22.345725Z","caller":"traceutil/trace.go:171","msg":"trace[1926019023] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"169.146894ms","start":"2026-04-07T00:16:22.176545Z","end":"2026-04-07T00:16:22.345692Z","steps":["trace[1926019023] 'process raft request' (duration: 128.646159ms)","trace[1926019023] 'compare' (duration: 40.286669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:24.521413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.559951ms","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-07T00:16:24.521504Z","caller":"traceutil/trace.go:171","msg":"trace[620391342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5535; }","duration":"171.700526ms","start":"2026-04-07T00:16:24.349784Z","end":"2026-04-07T00:16:24.521485Z","steps":["trace[620391342] 'range keys from in-memory index tree' (duration: 171.383327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:52.70576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-04-07T00:16:52.795777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"88.161735ms","hash":1797914403,"current-db-size-bytes":55705600,"current-db-size":"56 MB","current-db-size-in-use-bytes":29360128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:16:52.795883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797914403,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:16:58.384885Z","caller":"traceutil/trace.go:171","msg":"trace[447221871] linearizableReadLoop","detail":"{readStateIndex:6079; appliedIndex:6078; }","duration":"180.979798ms","start":"2026-04-07T00:16:58.203877Z","end":"2026-04-07T00:16:58.384856Z","steps":["trace[447221871] 'read index received' (duration: 180.784802ms)","trace[447221871] 'applied index is now lower than readState.Index' (duration: 193.726µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:58.385002Z","caller":"traceutil/trace.go:171","msg":"trace[1421507906] transaction","detail":"{read_only:false; response_revision:5857; number_of_response:1; }","duration":"187.391093ms","start":"2026-04-07T00:16:58.197567Z","end":"2026-04-07T00:16:58.384959Z","steps":["trace[1421507906] 'process raft request' (duration: 187.107735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:58.385071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.161444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:58.38511Z","caller":"traceutil/trace.go:171","msg":"trace[1686396124] range","detail":"{range_begin:/registry/cronjobs/kube-public/; range_end:/registry/cronjobs/kube-public0; response_count:0; response_revision:5857; }","duration":"181.264116ms","start":"2026-04-07T00:16:58.203832Z","end":"2026-04-07T00:16:58.385096Z","steps":["trace[1686396124] 'agreement among raft nodes before linearized reading' (duration: 181.169984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:58.547293Z","caller":"traceutil/trace.go:171","msg":"trace[881984028] linearizableReadLoop","detail":"{readStateIndex:6080; appliedIndex:6079; }","duration":"162.287599ms","start":"2026-04-07T00:16:58.384969Z","end":"2026-04-07T00:16:58.547257Z","steps":["trace[881984028] 'read index received' (duration: 153.466498ms)","trace[881984028] 'applied index is now lower than readState.Index' (duration: 8.819711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:58.547443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.621604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-07T00:16:58.547509Z","caller":"traceutil/trace.go:171","msg":"trace[114814711] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"229.352999ms","start":"2026-04-07T00:16:58.318124Z","end":"2026-04-07T00:16:58.547477Z","steps":["trace[114814711] 'process raft request' (duration: 220.328672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:58.547509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.545931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h2mwl\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-07T00:16:58.547556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.264892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-public/\" range_end:\"/registry/daemonsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:58.547552Z","caller":"traceutil/trace.go:171","msg":"trace[2087493731] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:5858; }","duration":"266.775399ms","start":"2026-04-07T00:16:58.280752Z","end":"2026-04-07T00:16:58.547527Z","steps":["trace[2087493731] 'agreement among raft nodes before linearized reading' (duration: 266.540783ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:58.547606Z","caller":"traceutil/trace.go:171","msg":"trace[397116414] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h2mwl; range_end:; response_count:1; response_revision:5858; }","duration":"182.723127ms","start":"2026-04-07T00:16:58.364863Z","end":"2026-04-07T00:16:58.547586Z","steps":["trace[397116414] 'agreement among raft nodes before linearized reading' (duration: 182.410048ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:58.54761Z","caller":"traceutil/trace.go:171","msg":"trace[533191435] range","detail":"{range_begin:/registry/daemonsets/kube-public/; range_end:/registry/daemonsets/kube-public0; response_count:0; response_revision:5858; }","duration":"293.365706ms","start":"2026-04-07T00:16:58.254225Z","end":"2026-04-07T00:16:58.547591Z","steps":["trace[533191435] 'agreement among raft nodes before linearized reading' (duration: 293.228092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:58.547762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.825411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T00:16:58.547792Z","caller":"traceutil/trace.go:171","msg":"trace[1976700260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5858; }","duration":"122.928625ms","start":"2026-04-07T00:16:58.424855Z","end":"2026-04-07T00:16:58.547784Z","steps":["trace[1976700260] 'agreement among raft nodes before linearized reading' (duration: 122.854363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:58.547787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.56368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-5c6mg\" ","response":"range_response_count:1 size:5484"} {"level":"info","ts":"2026-04-07T00:16:58.547822Z","caller":"traceutil/trace.go:171","msg":"trace[712928045] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-5c6mg; range_end:; response_count:1; response_revision:5858; }","duration":"312.654822ms","start":"2026-04-07T00:16:58.235158Z","end":"2026-04-07T00:16:58.547813Z","steps":["trace[712928045] 'agreement among raft nodes before linearized reading' (duration: 312.477646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:58.547871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:58.23512Z","time spent":"312.736023ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5507,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-5c6mg\" "}