{"level":"warn","ts":"2026-09-19T01:00:20.069194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:00:20.069333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.55: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://162.253.55.55:2380","--initial-cluster=instance=https://162.253.55.55:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.55:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.55: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-09-19T01:00:20.069405Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:00:20.069421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-19T01:00:20.069442Z","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-09-19T01:00:20.070439Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"]} {"level":"info","ts":"2026-09-19T01:00:20.070534Z","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://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.55: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-09-19T01:00:20.078097Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.3301ms"} {"level":"info","ts":"2026-09-19T01:00:20.085595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"357bf0025115def9","cluster-id":"5f69314e1ae3c800"} {"level":"info","ts":"2026-09-19T01:00:20.085678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:00:20.085697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 0"} {"level":"info","ts":"2026-09-19T01:00:20.085712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 357bf0025115def9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:00:20.085721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 1"} {"level":"info","ts":"2026-09-19T01:00:20.085805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"warn","ts":"2026-09-19T01:00:20.093528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:00:20.095192Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:00:20.096574Z","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-09-19T01:00:20.098915Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"357bf0025115def9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:00:20.104246Z","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-09-19T01:00:20.105007Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-19T01:00:20.105093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-19T01:00:20.105071Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"357bf0025115def9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:00:20.105241Z","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-09-19T01:00:20.105304Z","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-09-19T01:00:20.105322Z","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-09-19T01:00:20.105566Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"357bf0025115def9","initial-advertise-peer-urls":["https://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:00:20.105601Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:00:20.106197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"info","ts":"2026-09-19T01:00:20.106317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","added-peer-id":"357bf0025115def9","added-peer-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-19T01:00:20.386984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:00:20.387032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:00:20.387058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgPreVoteResp from 357bf0025115def9 at term 1"} {"level":"info","ts":"2026-09-19T01:00:20.387068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:00:20.387073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgVoteResp from 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-19T01:00:20.38708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became leader at term 2"} {"level":"info","ts":"2026-09-19T01:00:20.387087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 357bf0025115def9 elected leader 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-19T01:00:20.387918Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:00:20.388868Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"357bf0025115def9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.55:2379]}","request-path":"/0/members/357bf0025115def9/attributes","cluster-id":"5f69314e1ae3c800","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:00:20.389018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:00:20.389069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:00:20.38933Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:00:20.389586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:00:20.392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T01:00:20.392582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:00:20.392686Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:00:20.39272Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:00:20.393563Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.55:2379"} {"level":"warn","ts":"2026-09-19T01:00:27.028404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.01212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-09-19T01:00:27.028548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.643894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:00:27.028546Z","caller":"traceutil/trace.go:171","msg":"trace[1195851139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:267; }","duration":"184.222891ms","start":"2026-09-19T01:00:26.844299Z","end":"2026-09-19T01:00:27.028522Z","steps":["trace[1195851139] 'range keys from in-memory index tree' (duration: 183.743374ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.028631Z","caller":"traceutil/trace.go:171","msg":"trace[1278185553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:267; }","duration":"111.783821ms","start":"2026-09-19T01:00:26.916827Z","end":"2026-09-19T01:00:27.028611Z","steps":["trace[1278185553] 'range keys from in-memory index tree' (duration: 111.533587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.028404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.081061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.028832Z","caller":"traceutil/trace.go:171","msg":"trace[961092011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"163.552506ms","start":"2026-09-19T01:00:26.865253Z","end":"2026-09-19T01:00:27.028806Z","steps":["trace[961092011] 'range keys from in-memory index tree' (duration: 163.008937ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.255225Z","caller":"traceutil/trace.go:171","msg":"trace[347864364] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"322.483292ms","start":"2026-09-19T01:00:26.932705Z","end":"2026-09-19T01:00:27.255188Z","steps":["trace[347864364] 'process raft request' (duration: 322.181925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.255936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:26.932682Z","time spent":"322.622989ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:00:27.345439Z","caller":"traceutil/trace.go:171","msg":"trace[133426315] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"315.622216ms","start":"2026-09-19T01:00:27.029795Z","end":"2026-09-19T01:00:27.345417Z","steps":["trace[133426315] 'read index received' (duration: 225.359403ms)","trace[133426315] 'applied index is now lower than readState.Index' (duration: 90.261903ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:27.345548Z","caller":"traceutil/trace.go:171","msg":"trace[1998154173] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"411.862597ms","start":"2026-09-19T01:00:26.933677Z","end":"2026-09-19T01:00:27.345539Z","steps":["trace[1998154173] 'process raft request' (duration: 411.54103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.345638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:26.93366Z","time spent":"411.90993ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:00:27.345997Z","caller":"traceutil/trace.go:171","msg":"trace[769735753] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"312.832727ms","start":"2026-09-19T01:00:27.033152Z","end":"2026-09-19T01:00:27.345985Z","steps":["trace[769735753] 'process raft request' (duration: 312.12735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.34626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.033137Z","time spent":"312.88353ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:00:27.346443Z","caller":"traceutil/trace.go:171","msg":"trace[1677779591] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"289.149971ms","start":"2026-09-19T01:00:27.057283Z","end":"2026-09-19T01:00:27.346433Z","steps":["trace[1677779591] 'process raft request' (duration: 288.075954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.346568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.780218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.346597Z","caller":"traceutil/trace.go:171","msg":"trace[1171221151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:269; }","duration":"316.858132ms","start":"2026-09-19T01:00:27.029729Z","end":"2026-09-19T01:00:27.346587Z","steps":["trace[1171221151] 'agreement among raft nodes before linearized reading' (duration: 316.81353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.346619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.029711Z","time spent":"316.902345ms","remote":"127.0.0.1:40030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:00:27.346802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.454797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-19T01:00:27.346882Z","caller":"traceutil/trace.go:171","msg":"trace[1677666004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:269; }","duration":"315.596145ms","start":"2026-09-19T01:00:27.031264Z","end":"2026-09-19T01:00:27.34686Z","steps":["trace[1677666004] 'agreement among raft nodes before linearized reading' (duration: 315.415215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.34693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.031256Z","time spent":"315.660228ms","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-09-19T01:00:27.484707Z","caller":"traceutil/trace.go:171","msg":"trace[1031122283] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"125.630074ms","start":"2026-09-19T01:00:27.359057Z","end":"2026-09-19T01:00:27.484687Z","steps":["trace[1031122283] 'process raft request' (duration: 125.523978ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.486984Z","caller":"traceutil/trace.go:171","msg":"trace[378990957] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"127.064631ms","start":"2026-09-19T01:00:27.359907Z","end":"2026-09-19T01:00:27.486971Z","steps":["trace[378990957] 'process raft request' (duration: 127.006587ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:00.080353Z","caller":"traceutil/trace.go:171","msg":"trace[1100628522] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"119.533673ms","start":"2026-09-19T01:00:59.96057Z","end":"2026-09-19T01:01:00.080103Z","steps":["trace[1100628522] 'process raft request' (duration: 118.87688ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:00.333959Z","caller":"traceutil/trace.go:171","msg":"trace[66839816] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"119.486883ms","start":"2026-09-19T01:01:00.214445Z","end":"2026-09-19T01:01:00.333931Z","steps":["trace[66839816] 'read index received' (duration: 119.183786ms)","trace[66839816] 'applied index is now lower than readState.Index' (duration: 302.187µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:00.334108Z","caller":"traceutil/trace.go:171","msg":"trace[801414138] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"119.980308ms","start":"2026-09-19T01:01:00.214073Z","end":"2026-09-19T01:01:00.334053Z","steps":["trace[801414138] 'process raft request' (duration: 119.658711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:00.334197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.726231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T01:01:00.334234Z","caller":"traceutil/trace.go:171","msg":"trace[1164326591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"119.79889ms","start":"2026-09-19T01:01:00.214424Z","end":"2026-09-19T01:01:00.334223Z","steps":["trace[1164326591] 'agreement among raft nodes before linearized reading' (duration: 119.622042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:00.34146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.202748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:00.341507Z","caller":"traceutil/trace.go:171","msg":"trace[1360448579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:590; }","duration":"113.277527ms","start":"2026-09-19T01:01:00.228214Z","end":"2026-09-19T01:01:00.341492Z","steps":["trace[1360448579] 'agreement among raft nodes before linearized reading' (duration: 113.172738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:00.341895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.627874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:00.341923Z","caller":"traceutil/trace.go:171","msg":"trace[1476735470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:590; }","duration":"113.676224ms","start":"2026-09-19T01:01:00.228239Z","end":"2026-09-19T01:01:00.341916Z","steps":["trace[1476735470] 'agreement among raft nodes before linearized reading' (duration: 113.634314ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:00.869666Z","caller":"traceutil/trace.go:171","msg":"trace[748485372] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"110.782003ms","start":"2026-09-19T01:01:00.758812Z","end":"2026-09-19T01:01:00.869594Z","steps":["trace[748485372] 'process raft request' (duration: 110.339178ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:01.121979Z","caller":"traceutil/trace.go:171","msg":"trace[2117805421] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"241.270482ms","start":"2026-09-19T01:01:00.880684Z","end":"2026-09-19T01:01:01.121954Z","steps":["trace[2117805421] 'read index received' (duration: 163.808998ms)","trace[2117805421] 'applied index is now lower than readState.Index' (duration: 77.460674ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:01.122127Z","caller":"traceutil/trace.go:171","msg":"trace[1087117576] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"243.151093ms","start":"2026-09-19T01:01:00.878965Z","end":"2026-09-19T01:01:01.122116Z","steps":["trace[1087117576] 'process raft request' (duration: 165.618859ms)","trace[1087117576] 'compare' (duration: 77.255407ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:01:01.122307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.5182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.122388Z","caller":"traceutil/trace.go:171","msg":"trace[74325353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:601; }","duration":"241.615689ms","start":"2026-09-19T01:01:00.880755Z","end":"2026-09-19T01:01:01.122371Z","steps":["trace[74325353] 'agreement among raft nodes before linearized reading' (duration: 241.483771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.122526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.446898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.122575Z","caller":"traceutil/trace.go:171","msg":"trace[2018277121] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:601; }","duration":"105.530718ms","start":"2026-09-19T01:01:01.017021Z","end":"2026-09-19T01:01:01.122552Z","steps":["trace[2018277121] 'agreement among raft nodes before linearized reading' (duration: 105.430289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.122668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.046233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.122697Z","caller":"traceutil/trace.go:171","msg":"trace[833299703] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:601; }","duration":"106.094112ms","start":"2026-09-19T01:01:01.016596Z","end":"2026-09-19T01:01:01.122691Z","steps":["trace[833299703] 'agreement among raft nodes before linearized reading' (duration: 106.025393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.122741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.051295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.122765Z","caller":"traceutil/trace.go:171","msg":"trace[91096720] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:601; }","duration":"242.100635ms","start":"2026-09-19T01:01:00.880658Z","end":"2026-09-19T01:01:01.122758Z","steps":["trace[91096720] 'agreement among raft nodes before linearized reading' (duration: 242.042245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.122899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.44761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.122929Z","caller":"traceutil/trace.go:171","msg":"trace[994524639] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:601; }","duration":"106.504149ms","start":"2026-09-19T01:01:01.016417Z","end":"2026-09-19T01:01:01.122922Z","steps":["trace[994524639] 'agreement among raft nodes before linearized reading' (duration: 106.45486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.123259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.271403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.123287Z","caller":"traceutil/trace.go:171","msg":"trace[649835363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"126.365693ms","start":"2026-09-19T01:01:00.996914Z","end":"2026-09-19T01:01:01.12328Z","steps":["trace[649835363] 'agreement among raft nodes before linearized reading' (duration: 126.310333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.123489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.914807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.123519Z","caller":"traceutil/trace.go:171","msg":"trace[432546115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"127.969497ms","start":"2026-09-19T01:01:00.995541Z","end":"2026-09-19T01:01:01.12351Z","steps":["trace[432546115] 'agreement among raft nodes before linearized reading' (duration: 127.920147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.123716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.149345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.123749Z","caller":"traceutil/trace.go:171","msg":"trace[924351251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"128.230053ms","start":"2026-09-19T01:01:00.995507Z","end":"2026-09-19T01:01:01.123737Z","steps":["trace[924351251] 'agreement among raft nodes before linearized reading' (duration: 128.168204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.123969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.044648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.123998Z","caller":"traceutil/trace.go:171","msg":"trace[2101463730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"151.077338ms","start":"2026-09-19T01:01:00.972912Z","end":"2026-09-19T01:01:01.12399Z","steps":["trace[2101463730] 'agreement among raft nodes before linearized reading' (duration: 151.028918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.124164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.264635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.124206Z","caller":"traceutil/trace.go:171","msg":"trace[2135124509] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"151.302345ms","start":"2026-09-19T01:01:00.972881Z","end":"2026-09-19T01:01:01.124183Z","steps":["trace[2135124509] 'agreement among raft nodes before linearized reading' (duration: 151.254546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.124323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.455574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.124345Z","caller":"traceutil/trace.go:171","msg":"trace[94824797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"151.500874ms","start":"2026-09-19T01:01:00.972838Z","end":"2026-09-19T01:01:01.124339Z","steps":["trace[94824797] 'agreement among raft nodes before linearized reading' (duration: 151.464854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.124413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.233572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.124448Z","caller":"traceutil/trace.go:171","msg":"trace[238678826] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"162.300402ms","start":"2026-09-19T01:01:00.962124Z","end":"2026-09-19T01:01:01.124424Z","steps":["trace[238678826] 'agreement among raft nodes before linearized reading' (duration: 162.274542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:01.124556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.720269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:01.124576Z","caller":"traceutil/trace.go:171","msg":"trace[1329441387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:601; }","duration":"243.755708ms","start":"2026-09-19T01:01:00.880814Z","end":"2026-09-19T01:01:01.12457Z","steps":["trace[1329441387] 'agreement among raft nodes before linearized reading' (duration: 243.708659ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:06.394189Z","caller":"traceutil/trace.go:171","msg":"trace[502336774] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"130.243155ms","start":"2026-09-19T01:01:06.263915Z","end":"2026-09-19T01:01:06.394158Z","steps":["trace[502336774] 'process raft request' (duration: 128.802743ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:06.524629Z","caller":"traceutil/trace.go:171","msg":"trace[157387601] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"120.624497ms","start":"2026-09-19T01:01:06.403508Z","end":"2026-09-19T01:01:06.524132Z","steps":["trace[157387601] 'process raft request' (duration: 68.078672ms)","trace[157387601] 'compare' (duration: 52.309647ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:06.607626Z","caller":"traceutil/trace.go:171","msg":"trace[1206437840] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"134.76733ms","start":"2026-09-19T01:01:06.472675Z","end":"2026-09-19T01:01:06.607442Z","steps":["trace[1206437840] 'process raft request' (duration: 134.447432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.226709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.98068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:07.227014Z","caller":"traceutil/trace.go:171","msg":"trace[557373054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"357.359139ms","start":"2026-09-19T01:01:06.869631Z","end":"2026-09-19T01:01:07.226991Z","steps":["trace[557373054] 'range keys from in-memory index tree' (duration: 356.807961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.227202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:06.869601Z","time spent":"357.585577ms","remote":"127.0.0.1:40030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T01:01:07.244899Z","caller":"traceutil/trace.go:171","msg":"trace[865540761] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"196.140121ms","start":"2026-09-19T01:01:07.048737Z","end":"2026-09-19T01:01:07.244877Z","steps":["trace[865540761] 'process raft request' (duration: 195.308475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:24.075615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.367316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:24.075731Z","caller":"traceutil/trace.go:171","msg":"trace[402271439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"207.563848ms","start":"2026-09-19T01:01:23.868144Z","end":"2026-09-19T01:01:24.075708Z","steps":["trace[402271439] 'range keys from in-memory index tree' (duration: 207.205685ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:57.642797Z","caller":"traceutil/trace.go:171","msg":"trace[1021672372] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"116.512765ms","start":"2026-09-19T01:01:57.526253Z","end":"2026-09-19T01:01:57.642766Z","steps":["trace[1021672372] 'process raft request' (duration: 116.336211ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:57.792546Z","caller":"traceutil/trace.go:171","msg":"trace[1239498074] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"132.532949ms","start":"2026-09-19T01:01:57.659976Z","end":"2026-09-19T01:01:57.792509Z","steps":["trace[1239498074] 'process raft request' (duration: 113.404056ms)","trace[1239498074] 'compare' (duration: 19.006ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:22.631577Z","caller":"traceutil/trace.go:171","msg":"trace[1400778333] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"134.274302ms","start":"2026-09-19T01:02:22.497279Z","end":"2026-09-19T01:02:22.631553Z","steps":["trace[1400778333] 'read index received' (duration: 134.146948ms)","trace[1400778333] 'applied index is now lower than readState.Index' (duration: 125.794µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:22.63164Z","caller":"traceutil/trace.go:171","msg":"trace[295969903] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"141.422433ms","start":"2026-09-19T01:02:22.490186Z","end":"2026-09-19T01:02:22.631608Z","steps":["trace[295969903] 'process raft request' (duration: 141.225357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:22.631728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.426107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:22.631832Z","caller":"traceutil/trace.go:171","msg":"trace[787243595] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1459; }","duration":"134.579982ms","start":"2026-09-19T01:02:22.497233Z","end":"2026-09-19T01:02:22.631813Z","steps":["trace[787243595] 'agreement among raft nodes before linearized reading' (duration: 134.415687ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:45.294174Z","caller":"traceutil/trace.go:171","msg":"trace[1349323282] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"117.391384ms","start":"2026-09-19T01:02:45.176751Z","end":"2026-09-19T01:02:45.294142Z","steps":["trace[1349323282] 'process raft request' (duration: 59.334634ms)","trace[1349323282] 'compare' (duration: 57.863303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:45.67163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.847478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:02:45.671714Z","caller":"traceutil/trace.go:171","msg":"trace[1282603946] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"287.59035ms","start":"2026-09-19T01:02:45.384111Z","end":"2026-09-19T01:02:45.671701Z","steps":["trace[1282603946] 'read index received' (duration: 159.37631ms)","trace[1282603946] 'applied index is now lower than readState.Index' (duration: 128.21307ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:45.671775Z","caller":"traceutil/trace.go:171","msg":"trace[374202983] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"361.779017ms","start":"2026-09-19T01:02:45.309954Z","end":"2026-09-19T01:02:45.671733Z","steps":["trace[374202983] 'process raft request' (duration: 233.589177ms)","trace[374202983] 'compare' (duration: 127.747255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:45.671891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.785986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-09-19T01:02:45.671942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:45.309936Z","time spent":"361.914122ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:02:45.67192Z","caller":"traceutil/trace.go:171","msg":"trace[1750697203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"287.814287ms","start":"2026-09-19T01:02:45.384096Z","end":"2026-09-19T01:02:45.67191Z","steps":["trace[1750697203] 'agreement among raft nodes before linearized reading' (duration: 287.683702ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:45.858948Z","caller":"traceutil/trace.go:171","msg":"trace[1888889418] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"174.379138ms","start":"2026-09-19T01:02:45.684536Z","end":"2026-09-19T01:02:45.858915Z","steps":["trace[1888889418] 'process raft request' (duration: 174.054067ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:53.448987Z","caller":"traceutil/trace.go:171","msg":"trace[966726697] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"111.260704ms","start":"2026-09-19T01:02:53.337689Z","end":"2026-09-19T01:02:53.448949Z","steps":["trace[966726697] 'process raft request' (duration: 111.120829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:17.587357Z","caller":"traceutil/trace.go:171","msg":"trace[963508944] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"251.259439ms","start":"2026-09-19T01:03:17.336068Z","end":"2026-09-19T01:03:17.587327Z","steps":["trace[963508944] 'process raft request' (duration: 251.02357ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:17.760534Z","caller":"traceutil/trace.go:171","msg":"trace[1890943440] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"115.415495ms","start":"2026-09-19T01:03:17.645075Z","end":"2026-09-19T01:03:17.760491Z","steps":["trace[1890943440] 'process raft request' (duration: 100.010099ms)","trace[1890943440] 'compare' (duration: 15.179408ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:19.615984Z","caller":"traceutil/trace.go:171","msg":"trace[778123176] linearizableReadLoop","detail":"{readStateIndex:2735; appliedIndex:2734; }","duration":"189.662663ms","start":"2026-09-19T01:04:19.426296Z","end":"2026-09-19T01:04:19.615959Z","steps":["trace[778123176] 'read index received' (duration: 187.773593ms)","trace[778123176] 'applied index is now lower than readState.Index' (duration: 1.88774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:19.616183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.871421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-19T01:04:19.616221Z","caller":"traceutil/trace.go:171","msg":"trace[614798817] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2638; }","duration":"189.928403ms","start":"2026-09-19T01:04:19.426281Z","end":"2026-09-19T01:04:19.61621Z","steps":["trace[614798817] 'agreement among raft nodes before linearized reading' (duration: 189.773957ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:51.506388Z","caller":"traceutil/trace.go:171","msg":"trace[536533900] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"200.222175ms","start":"2026-09-19T01:04:51.306134Z","end":"2026-09-19T01:04:51.506356Z","steps":["trace[536533900] 'process raft request' (duration: 184.430037ms)","trace[536533900] 'compare' (duration: 15.622141ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:14.568357Z","caller":"traceutil/trace.go:171","msg":"trace[1791846956] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"116.852294ms","start":"2026-09-19T01:07:14.451488Z","end":"2026-09-19T01:07:14.56834Z","steps":["trace[1791846956] 'process raft request' (duration: 56.324147ms)","trace[1791846956] 'compare' (duration: 60.21329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:23.840735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.895406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-09-19T01:07:23.840794Z","caller":"traceutil/trace.go:171","msg":"trace[205340221] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4630; }","duration":"261.989959ms","start":"2026-09-19T01:07:23.57879Z","end":"2026-09-19T01:07:23.84078Z","steps":["trace[205340221] 'range keys from in-memory index tree' (duration: 261.762853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:23.84112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.146491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T01:07:23.841266Z","caller":"traceutil/trace.go:171","msg":"trace[922223882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4630; }","duration":"159.370427ms","start":"2026-09-19T01:07:23.681874Z","end":"2026-09-19T01:07:23.841244Z","steps":["trace[922223882] 'range keys from in-memory index tree' (duration: 158.67795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:23.841175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.015477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-19T01:07:23.841626Z","caller":"traceutil/trace.go:171","msg":"trace[1984435078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4630; }","duration":"165.519839ms","start":"2026-09-19T01:07:23.676093Z","end":"2026-09-19T01:07:23.841613Z","steps":["trace[1984435078] 'range keys from in-memory index tree' (duration: 164.428922ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:24.25417Z","caller":"traceutil/trace.go:171","msg":"trace[764293216] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"248.770319ms","start":"2026-09-19T01:07:24.005348Z","end":"2026-09-19T01:07:24.254118Z","steps":["trace[764293216] 'process raft request' (duration: 203.202585ms)","trace[764293216] 'compare' (duration: 45.190614ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:24.269568Z","caller":"traceutil/trace.go:171","msg":"trace[916383647] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4832; }","duration":"236.529294ms","start":"2026-09-19T01:07:24.033015Z","end":"2026-09-19T01:07:24.269544Z","steps":["trace[916383647] 'read index received' (duration: 175.557798ms)","trace[916383647] 'applied index is now lower than readState.Index' (duration: 60.970626ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:24.269793Z","caller":"traceutil/trace.go:171","msg":"trace[1461762391] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"252.551453ms","start":"2026-09-19T01:07:24.017216Z","end":"2026-09-19T01:07:24.269767Z","steps":["trace[1461762391] 'process raft request' (duration: 252.269986ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:24.269712Z","caller":"traceutil/trace.go:171","msg":"trace[1719715412] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"257.426414ms","start":"2026-09-19T01:07:24.012224Z","end":"2026-09-19T01:07:24.26965Z","steps":["trace[1719715412] 'process raft request' (duration: 257.126377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:24.269839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.796971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.55\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-19T01:07:24.269906Z","caller":"traceutil/trace.go:171","msg":"trace[859704727] range","detail":"{range_begin:/registry/masterleases/162.253.55.55; range_end:; response_count:1; response_revision:4636; }","duration":"236.924444ms","start":"2026-09-19T01:07:24.032971Z","end":"2026-09-19T01:07:24.269895Z","steps":["trace[859704727] 'agreement among raft nodes before linearized reading' (duration: 236.665888ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:24.433386Z","caller":"traceutil/trace.go:171","msg":"trace[927294046] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4836; }","duration":"124.954018ms","start":"2026-09-19T01:07:24.308392Z","end":"2026-09-19T01:07:24.433346Z","steps":["trace[927294046] 'read index received' (duration: 70.590286ms)","trace[927294046] 'applied index is now lower than readState.Index' (duration: 54.360502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:24.433683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.255166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-09-19T01:07:24.433725Z","caller":"traceutil/trace.go:171","msg":"trace[1705040278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4637; }","duration":"125.369448ms","start":"2026-09-19T01:07:24.308345Z","end":"2026-09-19T01:07:24.433714Z","steps":["trace[1705040278] 'agreement among raft nodes before linearized reading' (duration: 125.132503ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:36.032854Z","caller":"traceutil/trace.go:171","msg":"trace[1798367299] linearizableReadLoop","detail":"{readStateIndex:5038; appliedIndex:5037; }","duration":"107.570862ms","start":"2026-09-19T01:07:35.92526Z","end":"2026-09-19T01:07:36.032831Z","steps":["trace[1798367299] 'read index received' (duration: 107.359136ms)","trace[1798367299] 'applied index is now lower than readState.Index' (duration: 210.846µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:36.032931Z","caller":"traceutil/trace.go:171","msg":"trace[1153323239] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"125.859458ms","start":"2026-09-19T01:07:35.907041Z","end":"2026-09-19T01:07:36.0329Z","steps":["trace[1153323239] 'process raft request' (duration: 125.660573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:36.033046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.773138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-19T01:07:36.033085Z","caller":"traceutil/trace.go:171","msg":"trace[1694693974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4831; }","duration":"107.87068ms","start":"2026-09-19T01:07:35.925204Z","end":"2026-09-19T01:07:36.033075Z","steps":["trace[1694693974] 'agreement among raft nodes before linearized reading' (duration: 107.721937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:36.383553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.311983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:07:36.383673Z","caller":"traceutil/trace.go:171","msg":"trace[1585949322] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"237.32687ms","start":"2026-09-19T01:07:36.146326Z","end":"2026-09-19T01:07:36.383653Z","steps":["trace[1585949322] 'read index received' (duration: 67.718439ms)","trace[1585949322] 'applied index is now lower than readState.Index' (duration: 169.606711ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:36.384012Z","caller":"traceutil/trace.go:171","msg":"trace[1993591065] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"346.08018ms","start":"2026-09-19T01:07:36.037892Z","end":"2026-09-19T01:07:36.383972Z","steps":["trace[1993591065] 'process raft request' (duration: 176.230173ms)","trace[1993591065] 'compare' (duration: 168.845411ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:36.384062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.669991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-09-19T01:07:36.384064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.72163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-09-19T01:07:36.384133Z","caller":"traceutil/trace.go:171","msg":"trace[1960894480] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4832; }","duration":"203.789684ms","start":"2026-09-19T01:07:36.180323Z","end":"2026-09-19T01:07:36.384113Z","steps":["trace[1960894480] 'agreement among raft nodes before linearized reading' (duration: 203.589389ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:36.384136Z","caller":"traceutil/trace.go:171","msg":"trace[278524309] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4832; }","duration":"237.842663ms","start":"2026-09-19T01:07:36.146277Z","end":"2026-09-19T01:07:36.38412Z","steps":["trace[278524309] 'agreement among raft nodes before linearized reading' (duration: 237.651228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:36.384112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.780945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-09-19T01:07:36.384155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:07:36.037869Z","time spent":"346.207344ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:07:36.384226Z","caller":"traceutil/trace.go:171","msg":"trace[800307748] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4832; }","duration":"164.928938ms","start":"2026-09-19T01:07:36.219275Z","end":"2026-09-19T01:07:36.384204Z","steps":["trace[800307748] 'agreement among raft nodes before linearized reading' (duration: 164.657341ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:11.914167Z","caller":"traceutil/trace.go:171","msg":"trace[146782671] linearizableReadLoop","detail":"{readStateIndex:5429; appliedIndex:5428; }","duration":"108.332388ms","start":"2026-09-19T01:08:11.805811Z","end":"2026-09-19T01:08:11.914144Z","steps":["trace[146782671] 'read index received' (duration: 55.392535ms)","trace[146782671] 'applied index is now lower than readState.Index' (duration: 52.938883ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:11.91423Z","caller":"traceutil/trace.go:171","msg":"trace[1552268677] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"109.782951ms","start":"2026-09-19T01:08:11.804424Z","end":"2026-09-19T01:08:11.914207Z","steps":["trace[1552268677] 'process raft request' (duration: 56.866678ms)","trace[1552268677] 'compare' (duration: 52.756929ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:08:11.916307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.483242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360973"} {"level":"info","ts":"2026-09-19T01:08:11.916365Z","caller":"traceutil/trace.go:171","msg":"trace[1228391432] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5207; }","duration":"110.617216ms","start":"2026-09-19T01:08:11.805731Z","end":"2026-09-19T01:08:11.916348Z","steps":["trace[1228391432] 'agreement among raft nodes before linearized reading' (duration: 108.558486ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:10:20.733658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3053} {"level":"info","ts":"2026-09-19T01:10:20.8887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3053,"took":"153.71553ms","hash":45198717,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T01:10:20.88877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45198717,"revision":3053,"compact-revision":-1}