{"level":"warn","ts":"2026-10-15T18:55:13.870201Z","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-15T18:55:13.870349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86: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.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86: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-15T18:55:13.870445Z","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-15T18:55:13.870479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-10-15T18:55:13.87051Z","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-15T18:55:13.871212Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-10-15T18:55:13.871339Z","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.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86: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-15T18:55:13.876081Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.486511ms"} {"level":"info","ts":"2026-10-15T18:55:13.884721Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-10-15T18:55:13.88489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T18:55:13.884954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-10-15T18:55:13.884983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T18:55:13.885008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-10-15T18:55:13.885067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-10-15T18:55:13.892561Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T18:55:13.894016Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T18:55:13.895221Z","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-15T18:55:13.897028Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T18:55:13.897284Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T18:55:13.89803Z","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-15T18:55:13.898155Z","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-15T18:55:13.898169Z","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-15T18:55:13.900387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-10-15T18:55:13.900582Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-10-15T18:55:13.901612Z","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-15T18:55:13.901784Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-10-15T18:55:13.901844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-10-15T18:55:13.902109Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T18:55:13.902075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T18:55:14.88581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T18:55:14.885907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T18:55:14.885931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-10-15T18:55:14.885967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-10-15T18:55:14.885984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-10-15T18:55:14.886005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-10-15T18:55:14.886023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-10-15T18:55:14.887102Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:55:14.888361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:55:14.888394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:55:14.888333Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T18:55:14.889413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T18:55:14.88946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T18:55:14.889638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:55:14.890001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:55:14.890041Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:55:14.892999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T18:55:14.905903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"warn","ts":"2026-10-15T18:55:17.949735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.306442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-15T18:55:17.949862Z","caller":"traceutil/trace.go:171","msg":"trace[685683934] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"336.24206ms","start":"2026-10-15T18:55:17.613598Z","end":"2026-10-15T18:55:17.94984Z","steps":["trace[685683934] 'process raft request' (duration: 164.257547ms)","trace[685683934] 'compare' (duration: 171.143117ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:17.94997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:17.613578Z","time spent":"336.354133ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T18:55:18.348397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.225037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-15T18:55:18.348538Z","caller":"traceutil/trace.go:171","msg":"trace[1300378928] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"392.444687ms","start":"2026-10-15T18:55:17.956074Z","end":"2026-10-15T18:55:18.348519Z","steps":["trace[1300378928] 'process raft request' (duration: 198.763596ms)","trace[1300378928] 'compare' (duration: 192.953079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:18.348595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:17.956054Z","time spent":"392.515319ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T18:55:18.576543Z","caller":"traceutil/trace.go:171","msg":"trace[2002224675] linearizableReadLoop","detail":"{readStateIndex:110; appliedIndex:109; }","duration":"153.825771ms","start":"2026-10-15T18:55:18.422689Z","end":"2026-10-15T18:55:18.576515Z","steps":["trace[2002224675] 'read index received' (duration: 121.443286ms)","trace[2002224675] 'applied index is now lower than readState.Index' (duration: 32.381335ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:55:18.576618Z","caller":"traceutil/trace.go:171","msg":"trace[1803687893] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"221.996963ms","start":"2026-10-15T18:55:18.354587Z","end":"2026-10-15T18:55:18.576584Z","steps":["trace[1803687893] 'process raft request' (duration: 189.67707ms)","trace[1803687893] 'compare' (duration: 32.116807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:18.577088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.6251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T18:55:18.577159Z","caller":"traceutil/trace.go:171","msg":"trace[205447172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:106; }","duration":"103.745833ms","start":"2026-10-15T18:55:18.473397Z","end":"2026-10-15T18:55:18.577143Z","steps":["trace[205447172] 'agreement among raft nodes before linearized reading' (duration: 103.278659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:18.576937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.11483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-15T18:55:18.577662Z","caller":"traceutil/trace.go:171","msg":"trace[356924763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:106; }","duration":"154.972546ms","start":"2026-10-15T18:55:18.422667Z","end":"2026-10-15T18:55:18.57764Z","steps":["trace[356924763] 'agreement among raft nodes before linearized reading' (duration: 153.994697ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:55:59.959998Z","caller":"traceutil/trace.go:171","msg":"trace[479357527] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"163.10538ms","start":"2026-10-15T18:55:59.796837Z","end":"2026-10-15T18:55:59.959942Z","steps":["trace[479357527] 'process raft request' (duration: 104.669243ms)","trace[479357527] 'compare' (duration: 57.968027ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:56:01.346091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.025766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:56:01.346189Z","caller":"traceutil/trace.go:171","msg":"trace[1109174627] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"295.468363ms","start":"2026-10-15T18:56:01.050703Z","end":"2026-10-15T18:56:01.346172Z","steps":["trace[1109174627] 'process raft request' (duration: 13.077639ms)","trace[1109174627] 'compare' (duration: 281.908384ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:56:01.352992Z","caller":"traceutil/trace.go:171","msg":"trace[1300457278] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"174.15342ms","start":"2026-10-15T18:56:01.178811Z","end":"2026-10-15T18:56:01.352965Z","steps":["trace[1300457278] 'process raft request' (duration: 173.389384ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:56:07.747895Z","caller":"traceutil/trace.go:171","msg":"trace[2046471154] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"163.567275ms","start":"2026-10-15T18:56:07.584282Z","end":"2026-10-15T18:56:07.74785Z","steps":["trace[2046471154] 'process raft request' (duration: 163.402881ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:56:08.103662Z","caller":"traceutil/trace.go:171","msg":"trace[553987987] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"107.887104ms","start":"2026-10-15T18:56:07.99573Z","end":"2026-10-15T18:56:08.103617Z","steps":["trace[553987987] 'process raft request' (duration: 107.588177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:56:24.873395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.42015ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T18:56:37.690766Z","caller":"traceutil/trace.go:171","msg":"trace[1836389524] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"109.202125ms","start":"2026-10-15T18:56:37.581542Z","end":"2026-10-15T18:56:37.690744Z","steps":["trace[1836389524] 'process raft request' (duration: 108.539122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:56:51.4217Z","caller":"traceutil/trace.go:171","msg":"trace[602738010] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"140.861581ms","start":"2026-10-15T18:56:51.280805Z","end":"2026-10-15T18:56:51.421666Z","steps":["trace[602738010] 'process raft request' (duration: 140.635707ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:56:51.423416Z","caller":"traceutil/trace.go:171","msg":"trace[1700601976] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"102.950824ms","start":"2026-10-15T18:56:51.320435Z","end":"2026-10-15T18:56:51.423386Z","steps":["trace[1700601976] 'process raft request' (duration: 102.799891ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:56:55.231272Z","caller":"traceutil/trace.go:171","msg":"trace[1225226067] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"166.717988ms","start":"2026-10-15T18:56:55.064528Z","end":"2026-10-15T18:56:55.231246Z","steps":["trace[1225226067] 'process raft request' (duration: 166.531125ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:57:43.005538Z","caller":"traceutil/trace.go:171","msg":"trace[1838551537] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"270.749577ms","start":"2026-10-15T18:57:42.734759Z","end":"2026-10-15T18:57:43.005509Z","steps":["trace[1838551537] 'process raft request' (duration: 270.605945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:43.276264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.277136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:57:43.276412Z","caller":"traceutil/trace.go:171","msg":"trace[1963961666] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"540.641833ms","start":"2026-10-15T18:57:42.735746Z","end":"2026-10-15T18:57:43.276388Z","steps":["trace[1963961666] 'process raft request' (duration: 311.143093ms)","trace[1963961666] 'compare' (duration: 229.132764ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:57:43.276507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:57:42.735734Z","time spent":"540.744385ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:57:43.624156Z","caller":"traceutil/trace.go:171","msg":"trace[634484110] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"179.21162ms","start":"2026-10-15T18:57:43.444906Z","end":"2026-10-15T18:57:43.624117Z","steps":["trace[634484110] 'read index received' (duration: 178.839004ms)","trace[634484110] 'applied index is now lower than readState.Index' (duration: 371.036µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:57:43.624234Z","caller":"traceutil/trace.go:171","msg":"trace[905828858] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"254.793472ms","start":"2026-10-15T18:57:43.369407Z","end":"2026-10-15T18:57:43.6242Z","steps":["trace[905828858] 'process raft request' (duration: 254.438947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:43.62454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.980509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a34b19a4-65e6-4930-a4fb-cb9dc6407f2d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T18:57:43.624622Z","caller":"traceutil/trace.go:171","msg":"trace[399182131] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a34b19a4-65e6-4930-a4fb-cb9dc6407f2d; range_end:; response_count:1; response_revision:1908; }","duration":"145.093181ms","start":"2026-10-15T18:57:43.479509Z","end":"2026-10-15T18:57:43.624602Z","steps":["trace[399182131] 'agreement among raft nodes before linearized reading' (duration: 144.908708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:43.624617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.883449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T18:57:43.624673Z","caller":"traceutil/trace.go:171","msg":"trace[590351965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1908; }","duration":"128.983031ms","start":"2026-10-15T18:57:43.495674Z","end":"2026-10-15T18:57:43.624657Z","steps":["trace[590351965] 'agreement among raft nodes before linearized reading' (duration: 128.834539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:43.624811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.719858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:57:43.624987Z","caller":"traceutil/trace.go:171","msg":"trace[130781655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1908; }","duration":"180.108894ms","start":"2026-10-15T18:57:43.444848Z","end":"2026-10-15T18:57:43.624956Z","steps":["trace[130781655] 'agreement among raft nodes before linearized reading' (duration: 179.456364ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:57:57.330581Z","caller":"traceutil/trace.go:171","msg":"trace[1612729485] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"258.447437ms","start":"2026-10-15T18:57:57.072106Z","end":"2026-10-15T18:57:57.330554Z","steps":["trace[1612729485] 'process raft request' (duration: 258.237652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:57.506358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.018013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T18:57:57.506441Z","caller":"traceutil/trace.go:171","msg":"trace[1922517133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2017; }","duration":"157.161166ms","start":"2026-10-15T18:57:57.34926Z","end":"2026-10-15T18:57:57.506422Z","steps":["trace[1922517133] 'range keys from in-memory index tree' (duration: 156.755276ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:12.065671Z","caller":"traceutil/trace.go:171","msg":"trace[1103401474] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"112.800807ms","start":"2026-10-15T18:58:11.952843Z","end":"2026-10-15T18:58:12.065644Z","steps":["trace[1103401474] 'process raft request' (duration: 107.867194ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:12.205635Z","caller":"traceutil/trace.go:171","msg":"trace[1723041721] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"250.966697ms","start":"2026-10-15T18:58:11.954637Z","end":"2026-10-15T18:58:12.205604Z","steps":["trace[1723041721] 'process raft request' (duration: 250.427084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:17.748569Z","caller":"traceutil/trace.go:171","msg":"trace[1048889055] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"120.418043ms","start":"2026-10-15T18:58:17.628126Z","end":"2026-10-15T18:58:17.748544Z","steps":["trace[1048889055] 'read index received' (duration: 120.232448ms)","trace[1048889055] 'applied index is now lower than readState.Index' (duration: 184.415µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:58:17.748765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.620417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-15T18:58:17.74881Z","caller":"traceutil/trace.go:171","msg":"trace[1983448869] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2144; }","duration":"120.70628ms","start":"2026-10-15T18:58:17.628095Z","end":"2026-10-15T18:58:17.748801Z","steps":["trace[1983448869] 'agreement among raft nodes before linearized reading' (duration: 120.553636ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:17.748893Z","caller":"traceutil/trace.go:171","msg":"trace[327601200] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"128.856136ms","start":"2026-10-15T18:58:17.619988Z","end":"2026-10-15T18:58:17.748844Z","steps":["trace[327601200] 'process raft request' (duration: 128.399695ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:17.864626Z","caller":"traceutil/trace.go:171","msg":"trace[488449793] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"110.568848ms","start":"2026-10-15T18:58:17.754029Z","end":"2026-10-15T18:58:17.864598Z","steps":["trace[488449793] 'process raft request' (duration: 63.399844ms)","trace[488449793] 'compare' (duration: 47.058722ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:58:18.38361Z","caller":"traceutil/trace.go:171","msg":"trace[733328319] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"126.710428ms","start":"2026-10-15T18:58:18.256874Z","end":"2026-10-15T18:58:18.383584Z","steps":["trace[733328319] 'read index received' (duration: 126.491844ms)","trace[733328319] 'applied index is now lower than readState.Index' (duration: 217.504µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:58:18.383704Z","caller":"traceutil/trace.go:171","msg":"trace[821649849] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"159.889263ms","start":"2026-10-15T18:58:18.223787Z","end":"2026-10-15T18:58:18.383676Z","steps":["trace[821649849] 'process raft request' (duration: 159.653007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:58:18.384067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.164168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T18:58:18.384082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.236026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T18:58:18.384119Z","caller":"traceutil/trace.go:171","msg":"trace[799120518] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2151; }","duration":"127.291491ms","start":"2026-10-15T18:58:18.256811Z","end":"2026-10-15T18:58:18.384103Z","steps":["trace[799120518] 'agreement among raft nodes before linearized reading' (duration: 126.908723ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:18.384162Z","caller":"traceutil/trace.go:171","msg":"trace[1275469482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2151; }","duration":"103.379788ms","start":"2026-10-15T18:58:18.280764Z","end":"2026-10-15T18:58:18.384144Z","steps":["trace[1275469482] 'agreement among raft nodes before linearized reading' (duration: 103.084791ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:58:19.585231Z","caller":"traceutil/trace.go:171","msg":"trace[1628752702] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"118.228359ms","start":"2026-10-15T18:58:19.466953Z","end":"2026-10-15T18:58:19.585181Z","steps":["trace[1628752702] 'process raft request' (duration: 118.042455ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:59:38.123207Z","caller":"traceutil/trace.go:171","msg":"trace[765665564] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"126.261926ms","start":"2026-10-15T18:59:37.996929Z","end":"2026-10-15T18:59:38.123191Z","steps":["trace[765665564] 'process raft request' (duration: 126.154004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:38.310032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.342047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-15T18:59:38.31015Z","caller":"traceutil/trace.go:171","msg":"trace[68939136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2673; }","duration":"161.51106ms","start":"2026-10-15T18:59:38.148621Z","end":"2026-10-15T18:59:38.310133Z","steps":["trace[68939136] 'range keys from in-memory index tree' (duration: 161.195614ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:59:38.310376Z","caller":"traceutil/trace.go:171","msg":"trace[1768764865] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"132.23475ms","start":"2026-10-15T18:59:38.178118Z","end":"2026-10-15T18:59:38.310353Z","steps":["trace[1768764865] 'process raft request' (duration: 131.517566ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:14.910999Z","caller":"traceutil/trace.go:171","msg":"trace[793479514] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"167.282261ms","start":"2026-10-15T19:00:14.743692Z","end":"2026-10-15T19:00:14.910975Z","steps":["trace[793479514] 'process raft request' (duration: 167.138668ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:15.004874Z","caller":"traceutil/trace.go:171","msg":"trace[769368245] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"176.211753ms","start":"2026-10-15T19:00:14.828635Z","end":"2026-10-15T19:00:15.004847Z","steps":["trace[769368245] 'process raft request' (duration: 175.633923ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:15.133069Z","caller":"traceutil/trace.go:171","msg":"trace[2009162304] linearizableReadLoop","detail":"{readStateIndex:2995; appliedIndex:2994; }","duration":"128.463305ms","start":"2026-10-15T19:00:15.004582Z","end":"2026-10-15T19:00:15.133045Z","steps":["trace[2009162304] 'read index received' (duration: 53.04821ms)","trace[2009162304] 'applied index is now lower than readState.Index' (duration: 75.407834ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:00:15.133217Z","caller":"traceutil/trace.go:171","msg":"trace[2088870858] transaction","detail":"{read_only:false; response_revision:2879; number_of_response:1; }","duration":"220.839549ms","start":"2026-10-15T19:00:14.912315Z","end":"2026-10-15T19:00:15.133155Z","steps":["trace[2088870858] 'process raft request' (duration: 145.371523ms)","trace[2088870858] 'compare' (duration: 75.079899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:00:15.133339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.102614ms","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-15T19:00:15.133383Z","caller":"traceutil/trace.go:171","msg":"trace[1558586336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2879; }","duration":"141.219916ms","start":"2026-10-15T19:00:14.992153Z","end":"2026-10-15T19:00:15.133373Z","steps":["trace[1558586336] 'agreement among raft nodes before linearized reading' (duration: 140.982091ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:29.78734Z","caller":"traceutil/trace.go:171","msg":"trace[232260490] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"156.640439ms","start":"2026-10-15T19:04:29.630677Z","end":"2026-10-15T19:04:29.787317Z","steps":["trace[232260490] 'read index received' (duration: 104.143518ms)","trace[232260490] 'applied index is now lower than readState.Index' (duration: 52.496011ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:29.78745Z","caller":"traceutil/trace.go:171","msg":"trace[1553126212] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"165.468235ms","start":"2026-10-15T19:04:29.62197Z","end":"2026-10-15T19:04:29.787439Z","steps":["trace[1553126212] 'process raft request' (duration: 112.853182ms)","trace[1553126212] 'compare' (duration: 52.234857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:04:29.787821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.096866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:29.787857Z","caller":"traceutil/trace.go:171","msg":"trace[134856511] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4688; }","duration":"157.197108ms","start":"2026-10-15T19:04:29.63065Z","end":"2026-10-15T19:04:29.787847Z","steps":["trace[134856511] 'agreement among raft nodes before linearized reading' (duration: 157.092137ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:29.814891Z","caller":"traceutil/trace.go:171","msg":"trace[2028959150] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4688; }","duration":"176.305202ms","start":"2026-10-15T19:04:29.63856Z","end":"2026-10-15T19:04:29.814865Z","steps":["trace[2028959150] 'process raft request' (duration: 176.15726ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:29.843799Z","caller":"traceutil/trace.go:171","msg":"trace[515964138] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"106.870071ms","start":"2026-10-15T19:04:29.73688Z","end":"2026-10-15T19:04:29.84375Z","steps":["trace[515964138] 'process raft request' (duration: 106.735599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:29.844077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.714184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T19:04:29.844135Z","caller":"traceutil/trace.go:171","msg":"trace[1185051839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4689; }","duration":"175.809375ms","start":"2026-10-15T19:04:29.668311Z","end":"2026-10-15T19:04:29.84412Z","steps":["trace[1185051839] 'agreement among raft nodes before linearized reading' (duration: 175.687313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:29.844315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.651924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-4tqlf\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-10-15T19:04:29.844415Z","caller":"traceutil/trace.go:171","msg":"trace[1370563244] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-4tqlf; range_end:; response_count:1; response_revision:4689; }","duration":"166.771946ms","start":"2026-10-15T19:04:29.677623Z","end":"2026-10-15T19:04:29.844395Z","steps":["trace[1370563244] 'agreement among raft nodes before linearized reading' (duration: 166.258898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:29.918066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.393772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-15T19:04:29.918143Z","caller":"traceutil/trace.go:171","msg":"trace[1400376216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4694; }","duration":"126.497043ms","start":"2026-10-15T19:04:29.791628Z","end":"2026-10-15T19:04:29.918125Z","steps":["trace[1400376216] 'agreement among raft nodes before linearized reading' (duration: 126.26633ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:33.721087Z","caller":"traceutil/trace.go:171","msg":"trace[1703604385] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"169.177801ms","start":"2026-10-15T19:04:33.551883Z","end":"2026-10-15T19:04:33.721061Z","steps":["trace[1703604385] 'process raft request' (duration: 168.948877ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:33.721265Z","caller":"traceutil/trace.go:171","msg":"trace[73461231] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4996; }","duration":"161.95786ms","start":"2026-10-15T19:04:33.559294Z","end":"2026-10-15T19:04:33.721252Z","steps":["trace[73461231] 'read index received' (duration: 161.950109ms)","trace[73461231] 'applied index is now lower than readState.Index' (duration: 6.54µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:04:33.721435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.120972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T19:04:33.721623Z","caller":"traceutil/trace.go:171","msg":"trace[706080394] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4802; }","duration":"162.323555ms","start":"2026-10-15T19:04:33.559289Z","end":"2026-10-15T19:04:33.721612Z","steps":["trace[706080394] 'agreement among raft nodes before linearized reading' (duration: 162.0032ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:33.725433Z","caller":"traceutil/trace.go:171","msg":"trace[926741654] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"163.593135ms","start":"2026-10-15T19:04:33.561822Z","end":"2026-10-15T19:04:33.725416Z","steps":["trace[926741654] 'process raft request' (duration: 163.29346ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:33.725828Z","caller":"traceutil/trace.go:171","msg":"trace[1216365518] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"155.248855ms","start":"2026-10-15T19:04:33.570522Z","end":"2026-10-15T19:04:33.725771Z","steps":["trace[1216365518] 'process raft request' (duration: 154.821558ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:38.928874Z","caller":"traceutil/trace.go:171","msg":"trace[250506451] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"110.989191ms","start":"2026-10-15T19:04:38.817857Z","end":"2026-10-15T19:04:38.928846Z","steps":["trace[250506451] 'process raft request' (duration: 110.665786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:39.736367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.956842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tthz4\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-10-15T19:04:39.73645Z","caller":"traceutil/trace.go:171","msg":"trace[49217557] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tthz4; range_end:; response_count:1; response_revision:4973; }","duration":"176.114374ms","start":"2026-10-15T19:04:39.560314Z","end":"2026-10-15T19:04:39.736428Z","steps":["trace[49217557] 'range keys from in-memory index tree' (duration: 175.747768ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:39.736629Z","caller":"traceutil/trace.go:171","msg":"trace[219761969] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"174.905275ms","start":"2026-10-15T19:04:39.561693Z","end":"2026-10-15T19:04:39.736598Z","steps":["trace[219761969] 'process raft request' (duration: 153.598027ms)","trace[219761969] 'compare' (duration: 20.844031ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:45.328704Z","caller":"traceutil/trace.go:171","msg":"trace[2011885045] linearizableReadLoop","detail":"{readStateIndex:5272; appliedIndex:5271; }","duration":"101.700826ms","start":"2026-10-15T19:04:45.226975Z","end":"2026-10-15T19:04:45.328675Z","steps":["trace[2011885045] 'read index received' (duration: 101.460653ms)","trace[2011885045] 'applied index is now lower than readState.Index' (duration: 238.733µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:45.328769Z","caller":"traceutil/trace.go:171","msg":"trace[122079894] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"135.295673ms","start":"2026-10-15T19:04:45.193444Z","end":"2026-10-15T19:04:45.32874Z","steps":["trace[122079894] 'process raft request' (duration: 135.009459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:45.328952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.917909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:45.329002Z","caller":"traceutil/trace.go:171","msg":"trace[197455341] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5070; }","duration":"102.090082ms","start":"2026-10-15T19:04:45.226901Z","end":"2026-10-15T19:04:45.328991Z","steps":["trace[197455341] 'agreement among raft nodes before linearized reading' (duration: 101.94144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:45.597067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.883379ms","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-10-15T19:04:45.597151Z","caller":"traceutil/trace.go:171","msg":"trace[85555665] 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:5070; }","duration":"167.032822ms","start":"2026-10-15T19:04:45.430098Z","end":"2026-10-15T19:04:45.597131Z","steps":["trace[85555665] 'count revisions from in-memory index tree' (duration: 166.743217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:45.597375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.768361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:45.597566Z","caller":"traceutil/trace.go:171","msg":"trace[196016923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5070; }","duration":"153.025216ms","start":"2026-10-15T19:04:45.444494Z","end":"2026-10-15T19:04:45.597519Z","steps":["trace[196016923] 'range keys from in-memory index tree' (duration: 152.694451ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:45.914373Z","caller":"traceutil/trace.go:171","msg":"trace[1374232425] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"143.882665ms","start":"2026-10-15T19:04:45.77045Z","end":"2026-10-15T19:04:45.914333Z","steps":["trace[1374232425] 'process raft request' (duration: 143.663742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.169933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.888944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:46.170019Z","caller":"traceutil/trace.go:171","msg":"trace[271630569] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5075; }","duration":"231.021616ms","start":"2026-10-15T19:04:45.938979Z","end":"2026-10-15T19:04:46.170001Z","steps":["trace[271630569] 'count revisions from in-memory index tree' (duration: 230.784123ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.396563Z","caller":"traceutil/trace.go:171","msg":"trace[848507382] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"386.770983ms","start":"2026-10-15T19:04:46.009771Z","end":"2026-10-15T19:04:46.396542Z","steps":["trace[848507382] 'read index received' (duration: 386.57692ms)","trace[848507382] 'applied index is now lower than readState.Index' (duration: 193.173µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:46.396727Z","caller":"traceutil/trace.go:171","msg":"trace[867101688] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"403.150475ms","start":"2026-10-15T19:04:45.993528Z","end":"2026-10-15T19:04:46.396678Z","steps":["trace[867101688] 'process raft request' (duration: 402.871501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.396901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:45.99351Z","time spent":"403.293127ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:04:46.396896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.736277ms","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-10-15T19:04:46.396994Z","caller":"traceutil/trace.go:171","msg":"trace[1953106956] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5076; }","duration":"257.880499ms","start":"2026-10-15T19:04:46.139099Z","end":"2026-10-15T19:04:46.39698Z","steps":["trace[1953106956] 'agreement among raft nodes before linearized reading' (duration: 257.673046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.396976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.174737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T19:04:46.397021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.526636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T19:04:46.397229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.645051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-15T19:04:46.397267Z","caller":"traceutil/trace.go:171","msg":"trace[1166417476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5076; }","duration":"373.677221ms","start":"2026-10-15T19:04:46.023578Z","end":"2026-10-15T19:04:46.397255Z","steps":["trace[1166417476] 'agreement among raft nodes before linearized reading' (duration: 373.56945ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.397182Z","caller":"traceutil/trace.go:171","msg":"trace[262743927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5076; }","duration":"380.69856ms","start":"2026-10-15T19:04:46.016464Z","end":"2026-10-15T19:04:46.397163Z","steps":["trace[262743927] 'agreement among raft nodes before linearized reading' (duration: 380.263603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.397301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:46.023562Z","time spent":"373.731762ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:04:46.397318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:46.01646Z","time spent":"380.846492ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:04:46.397042Z","caller":"traceutil/trace.go:171","msg":"trace[341484898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5076; }","duration":"366.280408ms","start":"2026-10-15T19:04:46.030744Z","end":"2026-10-15T19:04:46.397025Z","steps":["trace[341484898] 'agreement among raft nodes before linearized reading' (duration: 366.058074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.397435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:46.030728Z","time spent":"366.700424ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T19:04:46.39712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.713074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:04:46.397514Z","caller":"traceutil/trace.go:171","msg":"trace[1800905200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5076; }","duration":"365.13821ms","start":"2026-10-15T19:04:46.032362Z","end":"2026-10-15T19:04:46.3975Z","steps":["trace[1800905200] 'agreement among raft nodes before linearized reading' (duration: 364.705213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.397542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.736488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-15T19:04:46.397603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:46.032342Z","time spent":"365.244001ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T19:04:46.397679Z","caller":"traceutil/trace.go:171","msg":"trace[1731542355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5076; }","duration":"387.90805ms","start":"2026-10-15T19:04:46.009748Z","end":"2026-10-15T19:04:46.397657Z","steps":["trace[1731542355] 'agreement among raft nodes before linearized reading' (duration: 386.929075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.397741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:46.009742Z","time spent":"387.968561ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T19:04:46.554368Z","caller":"traceutil/trace.go:171","msg":"trace[476984754] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"151.787946ms","start":"2026-10-15T19:04:46.402539Z","end":"2026-10-15T19:04:46.554326Z","steps":["trace[476984754] 'process raft request' (duration: 98.820651ms)","trace[476984754] 'compare' (duration: 52.706671ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:46.554411Z","caller":"traceutil/trace.go:171","msg":"trace[1861036773] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"151.588483ms","start":"2026-10-15T19:04:46.402802Z","end":"2026-10-15T19:04:46.55439Z","steps":["trace[1861036773] 'process raft request' (duration: 151.486171ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.554464Z","caller":"traceutil/trace.go:171","msg":"trace[14843001] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"151.672374ms","start":"2026-10-15T19:04:46.402738Z","end":"2026-10-15T19:04:46.554411Z","steps":["trace[14843001] 'process raft request' (duration: 151.473811ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.554635Z","caller":"traceutil/trace.go:171","msg":"trace[1745440988] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"151.764596ms","start":"2026-10-15T19:04:46.402836Z","end":"2026-10-15T19:04:46.5546Z","steps":["trace[1745440988] 'process raft request' (duration: 151.499932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:46.554647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.132855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:46.554716Z","caller":"traceutil/trace.go:171","msg":"trace[591343905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5081; }","duration":"110.284738ms","start":"2026-10-15T19:04:46.444416Z","end":"2026-10-15T19:04:46.554701Z","steps":["trace[591343905] 'agreement among raft nodes before linearized reading' (duration: 110.133865ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.554484Z","caller":"traceutil/trace.go:171","msg":"trace[1168702367] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5279; }","duration":"109.979863ms","start":"2026-10-15T19:04:46.444482Z","end":"2026-10-15T19:04:46.554462Z","steps":["trace[1168702367] 'read index received' (duration: 56.855655ms)","trace[1168702367] 'applied index is now lower than readState.Index' (duration: 53.123168ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:00.882554Z","caller":"traceutil/trace.go:171","msg":"trace[2041435876] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"348.165086ms","start":"2026-10-15T19:05:00.534364Z","end":"2026-10-15T19:05:00.882529Z","steps":["trace[2041435876] 'read index received' (duration: 308.358784ms)","trace[2041435876] 'applied index is now lower than readState.Index' (duration: 39.805402ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:00.882682Z","caller":"traceutil/trace.go:171","msg":"trace[1290027250] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"428.56576ms","start":"2026-10-15T19:05:00.4541Z","end":"2026-10-15T19:05:00.882666Z","steps":["trace[1290027250] 'process raft request' (duration: 388.700918ms)","trace[1290027250] 'compare' (duration: 39.604388ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:05:00.882778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:00.45408Z","time spent":"428.62589ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:05:00.882901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.637658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T19:05:00.882949Z","caller":"traceutil/trace.go:171","msg":"trace[708564305] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5258; }","duration":"265.75423ms","start":"2026-10-15T19:05:00.61718Z","end":"2026-10-15T19:05:00.882934Z","steps":["trace[708564305] 'agreement among raft nodes before linearized reading' (duration: 265.612758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:00.883076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.712594ms","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-10-15T19:05:00.883102Z","caller":"traceutil/trace.go:171","msg":"trace[270124121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5258; }","duration":"348.797435ms","start":"2026-10-15T19:05:00.534297Z","end":"2026-10-15T19:05:00.883094Z","steps":["trace[270124121] 'agreement among raft nodes before linearized reading' (duration: 348.720984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:00.883135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:00.534269Z","time spent":"348.856826ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:05:00.883207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.681522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-15T19:05:00.883247Z","caller":"traceutil/trace.go:171","msg":"trace[1148136764] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5258; }","duration":"260.739064ms","start":"2026-10-15T19:05:00.622497Z","end":"2026-10-15T19:05:00.883236Z","steps":["trace[1148136764] 'agreement among raft nodes before linearized reading' (duration: 260.634052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:00.883368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.551998ms","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-15T19:05:00.883439Z","caller":"traceutil/trace.go:171","msg":"trace[1396357946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5258; }","duration":"239.68434ms","start":"2026-10-15T19:05:00.643734Z","end":"2026-10-15T19:05:00.883418Z","steps":["trace[1396357946] 'agreement among raft nodes before linearized reading' (duration: 239.442657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:00.883732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.872718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-15T19:05:00.883718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.293498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T19:05:00.883767Z","caller":"traceutil/trace.go:171","msg":"trace[2114996805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5258; }","duration":"253.96481ms","start":"2026-10-15T19:05:00.629792Z","end":"2026-10-15T19:05:00.883757Z","steps":["trace[2114996805] 'agreement among raft nodes before linearized reading' (duration: 253.866778ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:00.883781Z","caller":"traceutil/trace.go:171","msg":"trace[78012033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5258; }","duration":"264.394709ms","start":"2026-10-15T19:05:00.619376Z","end":"2026-10-15T19:05:00.883771Z","steps":["trace[78012033] 'agreement among raft nodes before linearized reading' (duration: 263.864171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:00.883782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.00633ms","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-10-15T19:05:00.88382Z","caller":"traceutil/trace.go:171","msg":"trace[604121287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5258; }","duration":"254.07018ms","start":"2026-10-15T19:05:00.629738Z","end":"2026-10-15T19:05:00.883808Z","steps":["trace[604121287] 'agreement among raft nodes before linearized reading' (duration: 253.966149ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:01.041153Z","caller":"traceutil/trace.go:171","msg":"trace[1934468973] linearizableReadLoop","detail":"{readStateIndex:5472; appliedIndex:5469; }","duration":"134.397694ms","start":"2026-10-15T19:05:00.906738Z","end":"2026-10-15T19:05:01.041136Z","steps":["trace[1934468973] 'read index received' (duration: 107.207527ms)","trace[1934468973] 'applied index is now lower than readState.Index' (duration: 27.189497ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:01.041287Z","caller":"traceutil/trace.go:171","msg":"trace[857532301] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"146.474689ms","start":"2026-10-15T19:05:00.894803Z","end":"2026-10-15T19:05:01.041277Z","steps":["trace[857532301] 'process raft request' (duration: 146.263936ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:01.041323Z","caller":"traceutil/trace.go:171","msg":"trace[1306811055] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"147.359972ms","start":"2026-10-15T19:05:00.893943Z","end":"2026-10-15T19:05:01.041303Z","steps":["trace[1306811055] 'process raft request' (duration: 120.096214ms)","trace[1306811055] 'compare' (duration: 26.940413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:05:01.041615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.81475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-10-15T19:05:01.041634Z","caller":"traceutil/trace.go:171","msg":"trace[914000583] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"145.764038ms","start":"2026-10-15T19:05:00.895855Z","end":"2026-10-15T19:05:01.041619Z","steps":["trace[914000583] 'process raft request' (duration: 145.2466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:01.041637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.869321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11324"} {"level":"info","ts":"2026-10-15T19:05:01.041716Z","caller":"traceutil/trace.go:171","msg":"trace[25383581] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5265; }","duration":"135.003403ms","start":"2026-10-15T19:05:00.906693Z","end":"2026-10-15T19:05:01.041696Z","steps":["trace[25383581] 'agreement among raft nodes before linearized reading' (duration: 134.670447ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:01.042328Z","caller":"traceutil/trace.go:171","msg":"trace[1433619242] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5265; }","duration":"134.915871ms","start":"2026-10-15T19:05:00.906748Z","end":"2026-10-15T19:05:01.041664Z","steps":["trace[1433619242] 'agreement among raft nodes before linearized reading' (duration: 134.697488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:01.582738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.982294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:05:01.58295Z","caller":"traceutil/trace.go:171","msg":"trace[1618481663] linearizableReadLoop","detail":"{readStateIndex:5477; appliedIndex:5474; }","duration":"462.755184ms","start":"2026-10-15T19:05:01.120179Z","end":"2026-10-15T19:05:01.582934Z","steps":["trace[1618481663] 'read index received' (duration: 37.463825ms)","trace[1618481663] 'applied index is now lower than readState.Index' (duration: 425.290429ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:01.583008Z","caller":"traceutil/trace.go:171","msg":"trace[266553929] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"487.679066ms","start":"2026-10-15T19:05:01.095302Z","end":"2026-10-15T19:05:01.582981Z","steps":["trace[266553929] 'process raft request' (duration: 487.514424ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:01.583159Z","caller":"traceutil/trace.go:171","msg":"trace[2009361214] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"487.354171ms","start":"2026-10-15T19:05:01.095794Z","end":"2026-10-15T19:05:01.583148Z","steps":["trace[2009361214] 'process raft request' (duration: 487.079797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:01.583141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:01.095286Z","time spent":"487.793188ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:05:01.583211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:01.095772Z","time spent":"487.409092ms","remote":"127.0.0.1:41710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:05:01.583332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.512455ms","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-15T19:05:01.583323Z","caller":"traceutil/trace.go:171","msg":"trace[449891138] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"489.068468ms","start":"2026-10-15T19:05:01.094201Z","end":"2026-10-15T19:05:01.58327Z","steps":["trace[449891138] 'process raft request' (duration: 63.494025ms)","trace[449891138] 'compare' (duration: 424.536887ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:01.583396Z","caller":"traceutil/trace.go:171","msg":"trace[1853484676] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5270; }","duration":"108.626938ms","start":"2026-10-15T19:05:01.474748Z","end":"2026-10-15T19:05:01.583375Z","steps":["trace[1853484676] 'agreement among raft nodes before linearized reading' (duration: 108.492885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:01.583381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.17509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:05:01.583489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:01.094173Z","time spent":"489.21756ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:05:01.583522Z","caller":"traceutil/trace.go:171","msg":"trace[1214988258] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5270; }","duration":"463.354202ms","start":"2026-10-15T19:05:01.120141Z","end":"2026-10-15T19:05:01.583495Z","steps":["trace[1214988258] 'agreement among raft nodes before linearized reading' (duration: 463.153479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:01.583581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:01.120125Z","time spent":"463.443434ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-15T19:05:15.140662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-10-15T19:05:15.21591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"73.75437ms","hash":1140318454,"current-db-size-bytes":50397184,"current-db-size":"50 MB","current-db-size-in-use-bytes":27484160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T19:05:15.215992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1140318454,"revision":2879,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:08:53.152327Z","caller":"traceutil/trace.go:171","msg":"trace[739906185] transaction","detail":"{read_only:false; response_revision:6923; number_of_response:1; }","duration":"127.28634ms","start":"2026-10-15T19:08:53.024843Z","end":"2026-10-15T19:08:53.152129Z","steps":["trace[739906185] 'process raft request' (duration: 72.99738ms)","trace[739906185] 'compare' (duration: 54.055763ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:15.14761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5360} {"level":"info","ts":"2026-10-15T19:10:15.255822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5360,"took":"106.400643ms","hash":3055968538,"current-db-size-bytes":50397184,"current-db-size":"50 MB","current-db-size-in-use-bytes":18677760,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-15T19:10:15.255912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3055968538,"revision":5360,"compact-revision":2879}