{"level":"warn","ts":"2026-10-15T11:20:05.739032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:20:05.73918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.223: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.223:2380","--initial-cluster=instance=https://162.253.55.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.223: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-10-15T11:20:05.739314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:20:05.739339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-15T11:20:05.739443Z","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-10-15T11:20:05.740564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"]} {"level":"info","ts":"2026-10-15T11:20:05.740713Z","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://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.223: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-10-15T11:20:05.747554Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.58048ms"} {"level":"info","ts":"2026-10-15T11:20:05.7552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0f50f8a6c5f7d06","cluster-id":"62f129f77cf8e487"} {"level":"info","ts":"2026-10-15T11:20:05.755339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:20:05.755385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 0"} {"level":"info","ts":"2026-10-15T11:20:05.755413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0f50f8a6c5f7d06 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:20:05.755431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 1"} {"level":"info","ts":"2026-10-15T11:20:05.755493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"warn","ts":"2026-10-15T11:20:05.769404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:20:05.77159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:20:05.774024Z","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-10-15T11:20:05.777677Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0f50f8a6c5f7d06","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:20:05.777885Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0f50f8a6c5f7d06","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:20:05.778005Z","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-10-15T11:20:05.778205Z","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-10-15T11:20:05.778301Z","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-10-15T11:20:05.779569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"info","ts":"2026-10-15T11:20:05.779715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","added-peer-id":"a0f50f8a6c5f7d06","added-peer-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-15T11:20:05.782012Z","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-10-15T11:20:05.782373Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0f50f8a6c5f7d06","initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:20:05.782418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:20:05.782504Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-15T11:20:05.782536Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-15T11:20:05.956031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:20:05.956193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:20:05.956234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgPreVoteResp from a0f50f8a6c5f7d06 at term 1"} {"level":"info","ts":"2026-10-15T11:20:05.956265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:20:05.956282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgVoteResp from a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-15T11:20:05.956312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became leader at term 2"} {"level":"info","ts":"2026-10-15T11:20:05.956331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0f50f8a6c5f7d06 elected leader a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-15T11:20:05.957726Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:20:05.959006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:20:05.959007Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0f50f8a6c5f7d06","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.223:2379]}","request-path":"/0/members/a0f50f8a6c5f7d06/attributes","cluster-id":"62f129f77cf8e487","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:20:05.959137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:20:05.959345Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:20:05.959422Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:20:05.960366Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:20:05.960601Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:20:05.960645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:20:05.963882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:20:05.964362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.223:2379"} {"level":"info","ts":"2026-10-15T11:20:52.784701Z","caller":"traceutil/trace.go:171","msg":"trace[1726935055] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"201.082538ms","start":"2026-10-15T11:20:52.583591Z","end":"2026-10-15T11:20:52.784674Z","steps":["trace[1726935055] 'process raft request' (duration: 200.918325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:59.217318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.997737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:20:59.217608Z","caller":"traceutil/trace.go:171","msg":"trace[1027168917] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"176.985616ms","start":"2026-10-15T11:20:59.040581Z","end":"2026-10-15T11:20:59.217566Z","steps":["trace[1027168917] 'process raft request' (duration: 63.875376ms)","trace[1027168917] 'compare' (duration: 111.860394ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:20:59.221066Z","caller":"traceutil/trace.go:171","msg":"trace[1201025078] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"153.821145ms","start":"2026-10-15T11:20:59.067226Z","end":"2026-10-15T11:20:59.221047Z","steps":["trace[1201025078] 'process raft request' (duration: 153.571039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:59.548765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.897894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h4vxk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T11:20:59.548894Z","caller":"traceutil/trace.go:171","msg":"trace[696032162] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h4vxk; range_end:; response_count:1; response_revision:654; }","duration":"184.072967ms","start":"2026-10-15T11:20:59.364783Z","end":"2026-10-15T11:20:59.548856Z","steps":["trace[696032162] 'range keys from in-memory index tree' (duration: 183.608027ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:59.992657Z","caller":"traceutil/trace.go:171","msg":"trace[1372492569] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"416.132673ms","start":"2026-10-15T11:20:59.57649Z","end":"2026-10-15T11:20:59.992622Z","steps":["trace[1372492569] 'process raft request' (duration: 415.934609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:59.992946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:20:59.576448Z","time spent":"416.313357ms","remote":"127.0.0.1:39278","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-10-15T11:21:00.07095Z","caller":"traceutil/trace.go:171","msg":"trace[177279396] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"185.062358ms","start":"2026-10-15T11:20:59.885799Z","end":"2026-10-15T11:21:00.070861Z","steps":["trace[177279396] 'read index received' (duration: 106.830977ms)","trace[177279396] 'applied index is now lower than readState.Index' (duration: 78.229991ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:21:00.071081Z","caller":"traceutil/trace.go:171","msg":"trace[1724224676] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"359.227835ms","start":"2026-10-15T11:20:59.711701Z","end":"2026-10-15T11:21:00.070929Z","steps":["trace[1724224676] 'process raft request' (duration: 342.851867ms)","trace[1724224676] 'compare' (duration: 15.765665ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:21:00.071141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.319803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:21:00.071225Z","caller":"traceutil/trace.go:171","msg":"trace[1854877616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"185.448946ms","start":"2026-10-15T11:20:59.885748Z","end":"2026-10-15T11:21:00.071197Z","steps":["trace[1854877616] 'agreement among raft nodes before linearized reading' (duration: 185.275473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:21:00.07128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:20:59.711652Z","time spent":"359.536341ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:21:01.63432Z","caller":"traceutil/trace.go:171","msg":"trace[1320438364] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"111.064008ms","start":"2026-10-15T11:21:01.523211Z","end":"2026-10-15T11:21:01.634275Z","steps":["trace[1320438364] 'process raft request' (duration: 46.6086ms)","trace[1320438364] 'compare' (duration: 64.274724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:21:45.052691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.399334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:21:45.052915Z","caller":"traceutil/trace.go:171","msg":"trace[538253785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"166.68099ms","start":"2026-10-15T11:21:44.886168Z","end":"2026-10-15T11:21:45.052849Z","steps":["trace[538253785] 'range keys from in-memory index tree' (duration: 166.230701ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:22:31.56181Z","caller":"traceutil/trace.go:171","msg":"trace[575251457] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"196.522454ms","start":"2026-10-15T11:22:31.365252Z","end":"2026-10-15T11:22:31.561774Z","steps":["trace[575251457] 'read index received' (duration: 196.191968ms)","trace[575251457] 'applied index is now lower than readState.Index' (duration: 328.506µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:22:31.561992Z","caller":"traceutil/trace.go:171","msg":"trace[488507910] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"204.547835ms","start":"2026-10-15T11:22:31.357377Z","end":"2026-10-15T11:22:31.561924Z","steps":["trace[488507910] 'process raft request' (duration: 204.168827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:22:31.562232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.925743ms","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-10-15T11:22:31.562358Z","caller":"traceutil/trace.go:171","msg":"trace[786103389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1877; }","duration":"197.138987ms","start":"2026-10-15T11:22:31.365182Z","end":"2026-10-15T11:22:31.562321Z","steps":["trace[786103389] 'agreement among raft nodes before linearized reading' (duration: 196.767729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:15.275442Z","caller":"traceutil/trace.go:171","msg":"trace[127613407] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"238.926711ms","start":"2026-10-15T11:23:15.036489Z","end":"2026-10-15T11:23:15.275415Z","steps":["trace[127613407] 'read index received' (duration: 238.855809ms)","trace[127613407] 'applied index is now lower than readState.Index' (duration: 69.402µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:23:15.27559Z","caller":"traceutil/trace.go:171","msg":"trace[377080489] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"239.985574ms","start":"2026-10-15T11:23:15.035573Z","end":"2026-10-15T11:23:15.275559Z","steps":["trace[377080489] 'process raft request' (duration: 239.700188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:15.27578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.184807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T11:23:15.275859Z","caller":"traceutil/trace.go:171","msg":"trace[2097850282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2221; }","duration":"239.360101ms","start":"2026-10-15T11:23:15.036483Z","end":"2026-10-15T11:23:15.275843Z","steps":["trace[2097850282] 'agreement among raft nodes before linearized reading' (duration: 239.074895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:15.522563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.479799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:23:15.522767Z","caller":"traceutil/trace.go:171","msg":"trace[1358668386] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"242.751233ms","start":"2026-10-15T11:23:15.279998Z","end":"2026-10-15T11:23:15.522749Z","steps":["trace[1358668386] 'process raft request' (duration: 242.687902ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:15.522843Z","caller":"traceutil/trace.go:171","msg":"trace[1142431176] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2299; }","duration":"247.256449ms","start":"2026-10-15T11:23:15.27553Z","end":"2026-10-15T11:23:15.522786Z","steps":["trace[1142431176] 'read index received' (duration: 24.437852ms)","trace[1142431176] 'applied index is now lower than readState.Index' (duration: 222.815057ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:23:15.52304Z","caller":"traceutil/trace.go:171","msg":"trace[1120308374] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"312.203829ms","start":"2026-10-15T11:23:15.210492Z","end":"2026-10-15T11:23:15.522696Z","steps":["trace[1120308374] 'process raft request' (duration: 89.455754ms)","trace[1120308374] 'compare' (duration: 222.347306ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:23:15.523157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.949029ms","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":"info","ts":"2026-10-15T11:23:15.523244Z","caller":"traceutil/trace.go:171","msg":"trace[124998291] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2223; }","duration":"432.070342ms","start":"2026-10-15T11:23:15.091152Z","end":"2026-10-15T11:23:15.523223Z","steps":["trace[124998291] 'agreement among raft nodes before linearized reading' (duration: 431.761475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:15.52324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.346331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-15T11:23:15.523292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:15.091141Z","time spent":"432.141923ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-15T11:23:15.523336Z","caller":"traceutil/trace.go:171","msg":"trace[925941452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2223; }","duration":"448.527774ms","start":"2026-10-15T11:23:15.074774Z","end":"2026-10-15T11:23:15.523302Z","steps":["trace[925941452] 'agreement among raft nodes before linearized reading' (duration: 448.175766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:15.523383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:15.074747Z","time spent":"448.625476ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:23:15.523576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:15.210459Z","time spent":"312.75024ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:23:15.5237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.70853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18deb023f281e6e1\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-10-15T11:23:15.523862Z","caller":"traceutil/trace.go:171","msg":"trace[2001519152] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18deb023f281e6e1; range_end:; response_count:1; response_revision:2223; }","duration":"341.854602ms","start":"2026-10-15T11:23:15.181929Z","end":"2026-10-15T11:23:15.523784Z","steps":["trace[2001519152] 'agreement among raft nodes before linearized reading' (duration: 340.978993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:15.523947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:15.181906Z","time spent":"342.006276ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18deb023f281e6e1\" "} {"level":"info","ts":"2026-10-15T11:23:15.525793Z","caller":"traceutil/trace.go:171","msg":"trace[679084926] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"108.125504ms","start":"2026-10-15T11:23:15.417644Z","end":"2026-10-15T11:23:15.52577Z","steps":["trace[679084926] 'process raft request' (duration: 107.980931ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.939895Z","caller":"traceutil/trace.go:171","msg":"trace[428500663] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2622; }","duration":"210.411994ms","start":"2026-10-15T11:23:51.729449Z","end":"2026-10-15T11:23:51.939861Z","steps":["trace[428500663] 'read index received' (duration: 5.470146ms)","trace[428500663] 'applied index is now lower than readState.Index' (duration: 204.940158ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:23:51.93988Z","caller":"traceutil/trace.go:171","msg":"trace[421595660] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"211.084699ms","start":"2026-10-15T11:23:51.728713Z","end":"2026-10-15T11:23:51.939798Z","steps":["trace[421595660] 'process raft request' (duration: 210.67501ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.939965Z","caller":"traceutil/trace.go:171","msg":"trace[1917754133] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"206.93259ms","start":"2026-10-15T11:23:51.732989Z","end":"2026-10-15T11:23:51.939921Z","steps":["trace[1917754133] 'process raft request' (duration: 206.693125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:51.940213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.737131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-15T11:23:51.940282Z","caller":"traceutil/trace.go:171","msg":"trace[1725060531] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2528; }","duration":"210.832043ms","start":"2026-10-15T11:23:51.729434Z","end":"2026-10-15T11:23:51.940266Z","steps":["trace[1725060531] 'agreement among raft nodes before linearized reading' (duration: 210.588798ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:27.746658Z","caller":"traceutil/trace.go:171","msg":"trace[1728182631] linearizableReadLoop","detail":"{readStateIndex:2880; appliedIndex:2879; }","duration":"119.604523ms","start":"2026-10-15T11:24:27.627022Z","end":"2026-10-15T11:24:27.746627Z","steps":["trace[1728182631] 'read index received' (duration: 39.638903ms)","trace[1728182631] 'applied index is now lower than readState.Index' (duration: 79.96458ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:24:27.746692Z","caller":"traceutil/trace.go:171","msg":"trace[1939770761] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"197.555451ms","start":"2026-10-15T11:24:27.549105Z","end":"2026-10-15T11:24:27.74666Z","steps":["trace[1939770761] 'process raft request' (duration: 117.660372ms)","trace[1939770761] 'compare' (duration: 79.732215ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:24:27.746894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.847778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:24:27.746991Z","caller":"traceutil/trace.go:171","msg":"trace[1533805704] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2769; }","duration":"120.000491ms","start":"2026-10-15T11:24:27.626969Z","end":"2026-10-15T11:24:27.74697Z","steps":["trace[1533805704] 'agreement among raft nodes before linearized reading' (duration: 119.811677ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:34.771268Z","caller":"traceutil/trace.go:171","msg":"trace[1207002561] linearizableReadLoop","detail":"{readStateIndex:3533; appliedIndex:3532; }","duration":"176.761366ms","start":"2026-10-15T11:25:34.59447Z","end":"2026-10-15T11:25:34.771232Z","steps":["trace[1207002561] 'read index received' (duration: 137.091855ms)","trace[1207002561] 'applied index is now lower than readState.Index' (duration: 39.668631ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:34.77141Z","caller":"traceutil/trace.go:171","msg":"trace[1195520322] transaction","detail":"{read_only:false; response_revision:3393; number_of_response:1; }","duration":"189.163358ms","start":"2026-10-15T11:25:34.582191Z","end":"2026-10-15T11:25:34.771355Z","steps":["trace[1195520322] 'process raft request' (duration: 149.443047ms)","trace[1195520322] 'compare' (duration: 39.465126ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:25:34.771615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.823135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:25:34.771726Z","caller":"traceutil/trace.go:171","msg":"trace[1729061682] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3393; }","duration":"117.002349ms","start":"2026-10-15T11:25:34.654688Z","end":"2026-10-15T11:25:34.771691Z","steps":["trace[1729061682] 'agreement among raft nodes before linearized reading' (duration: 116.770144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:34.771709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.206736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-15T11:25:34.771787Z","caller":"traceutil/trace.go:171","msg":"trace[1555093625] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3393; }","duration":"177.361949ms","start":"2026-10-15T11:25:34.594406Z","end":"2026-10-15T11:25:34.771768Z","steps":["trace[1555093625] 'agreement among raft nodes before linearized reading' (duration: 177.006521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:58.038879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.100415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T11:26:58.03898Z","caller":"traceutil/trace.go:171","msg":"trace[571904863] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4618; }","duration":"170.286469ms","start":"2026-10-15T11:26:57.868672Z","end":"2026-10-15T11:26:58.038959Z","steps":["trace[571904863] 'range keys from in-memory index tree' (duration: 169.723188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:58.039079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.627791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-381cb439-20a2-47e5-82f3-511228e878b9\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T11:26:58.039208Z","caller":"traceutil/trace.go:171","msg":"trace[1381780155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-381cb439-20a2-47e5-82f3-511228e878b9; range_end:; response_count:1; response_revision:4618; }","duration":"207.850295ms","start":"2026-10-15T11:26:57.831327Z","end":"2026-10-15T11:26:58.039177Z","steps":["trace[1381780155] 'range keys from in-memory index tree' (duration: 207.301053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:58.039236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.982414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-15T11:26:58.039344Z","caller":"traceutil/trace.go:171","msg":"trace[75368970] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4618; }","duration":"172.169728ms","start":"2026-10-15T11:26:57.867152Z","end":"2026-10-15T11:26:58.039322Z","steps":["trace[75368970] 'range keys from in-memory index tree' (duration: 171.680718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:02.609803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.722805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:02.609935Z","caller":"traceutil/trace.go:171","msg":"trace[501082516] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4694; }","duration":"134.946639ms","start":"2026-10-15T11:27:02.474953Z","end":"2026-10-15T11:27:02.6099Z","steps":["trace[501082516] 'count revisions from in-memory index tree' (duration: 134.596122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:02.610814Z","caller":"traceutil/trace.go:171","msg":"trace[1858688612] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"121.71793ms","start":"2026-10-15T11:27:02.489005Z","end":"2026-10-15T11:27:02.610723Z","steps":["trace[1858688612] 'process raft request' (duration: 55.499087ms)","trace[1858688612] 'compare' (duration: 65.706222ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:38.93817Z","caller":"traceutil/trace.go:171","msg":"trace[1325569413] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5289; }","duration":"158.943338ms","start":"2026-10-15T11:27:38.779193Z","end":"2026-10-15T11:27:38.938136Z","steps":["trace[1325569413] 'read index received' (duration: 138.278351ms)","trace[1325569413] 'applied index is now lower than readState.Index' (duration: 20.662817ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:38.938376Z","caller":"traceutil/trace.go:171","msg":"trace[74213031] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"163.621236ms","start":"2026-10-15T11:27:38.774739Z","end":"2026-10-15T11:27:38.93836Z","steps":["trace[74213031] 'process raft request' (duration: 142.719623ms)","trace[74213031] 'compare' (duration: 20.236189ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:27:38.938925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.702984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1764"} {"level":"info","ts":"2026-10-15T11:27:38.939068Z","caller":"traceutil/trace.go:171","msg":"trace[1921333738] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:5072; }","duration":"159.901418ms","start":"2026-10-15T11:27:38.779126Z","end":"2026-10-15T11:27:38.939028Z","steps":["trace[1921333738] 'agreement among raft nodes before linearized reading' (duration: 159.538931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:44.198712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.085207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/\" range_end:\"/registry/events/ingress-nginx0\" limit:500 ","response":"range_response_count:30 size:23669"} {"level":"info","ts":"2026-10-15T11:27:44.198857Z","caller":"traceutil/trace.go:171","msg":"trace[1055722496] range","detail":"{range_begin:/registry/events/ingress-nginx/; range_end:/registry/events/ingress-nginx0; response_count:30; response_revision:5106; }","duration":"180.296241ms","start":"2026-10-15T11:27:44.018536Z","end":"2026-10-15T11:27:44.198832Z","steps":["trace[1055722496] 'range keys from in-memory index tree' (duration: 179.637097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:44.198858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.748534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/\" range_end:\"/registry/statefulsets/monitoring0\" limit:500 ","response":"range_response_count:3 size:32712"} {"level":"info","ts":"2026-10-15T11:27:44.198995Z","caller":"traceutil/trace.go:171","msg":"trace[1157905404] range","detail":"{range_begin:/registry/statefulsets/monitoring/; range_end:/registry/statefulsets/monitoring0; response_count:3; response_revision:5106; }","duration":"149.980178ms","start":"2026-10-15T11:27:44.048983Z","end":"2026-10-15T11:27:44.198963Z","steps":["trace[1157905404] 'range keys from in-memory index tree' (duration: 149.287554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:44.19886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.906832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 ","response":"range_response_count:6 size:164448"} {"level":"info","ts":"2026-10-15T11:27:44.199087Z","caller":"traceutil/trace.go:171","msg":"trace[1052830094] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:6; response_revision:5106; }","duration":"163.205118ms","start":"2026-10-15T11:27:44.035855Z","end":"2026-10-15T11:27:44.19906Z","steps":["trace[1052830094] 'range keys from in-memory index tree' (duration: 162.187206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:44.364848Z","caller":"traceutil/trace.go:171","msg":"trace[1794913907] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"101.154583ms","start":"2026-10-15T11:27:44.263661Z","end":"2026-10-15T11:27:44.364816Z","steps":["trace[1794913907] 'process raft request' (duration: 100.940159ms)"],"step_count":1}