{"level":"warn","ts":"2026-10-02T15:25:06.533901Z","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-02T15:25:06.533975Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.116: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.116:2380","--initial-cluster=instance=https://162.253.55.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.116: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-02T15:25:06.534034Z","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-02T15:25:06.534041Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-10-02T15:25:06.534059Z","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-02T15:25:06.534638Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"]} {"level":"info","ts":"2026-10-02T15:25:06.534705Z","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.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.116: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-02T15:25:06.539638Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.659766ms"} {"level":"info","ts":"2026-10-02T15:25:06.547497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9755887b76c83964","cluster-id":"5ee78cd75a03071"} {"level":"info","ts":"2026-10-02T15:25:06.547544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T15:25:06.547559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 0"} {"level":"info","ts":"2026-10-02T15:25:06.547569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9755887b76c83964 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T15:25:06.547575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 1"} {"level":"info","ts":"2026-10-02T15:25:06.547609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"warn","ts":"2026-10-02T15:25:06.553388Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T15:25:06.55469Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T15:25:06.55587Z","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-02T15:25:06.557836Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9755887b76c83964","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T15:25:06.55814Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9755887b76c83964","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T15:25:06.558284Z","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-02T15:25:06.558443Z","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-02T15:25:06.558486Z","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-02T15:25:06.560439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"info","ts":"2026-10-02T15:25:06.560516Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","added-peer-id":"9755887b76c83964","added-peer-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-10-02T15:25:06.561372Z","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-02T15:25:06.561591Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9755887b76c83964","initial-advertise-peer-urls":["https://162.253.55.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T15:25:06.561629Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T15:25:06.56174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-10-02T15:25:06.561767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-10-02T15:25:06.748776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T15:25:06.748843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T15:25:06.748869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgPreVoteResp from 9755887b76c83964 at term 1"} {"level":"info","ts":"2026-10-02T15:25:06.748889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became candidate at term 2"} {"level":"info","ts":"2026-10-02T15:25:06.7489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgVoteResp from 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-10-02T15:25:06.748915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became leader at term 2"} {"level":"info","ts":"2026-10-02T15:25:06.748932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9755887b76c83964 elected leader 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-10-02T15:25:06.750987Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9755887b76c83964","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.116:2379]}","request-path":"/0/members/9755887b76c83964/attributes","cluster-id":"5ee78cd75a03071","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T15:25:06.751044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:25:06.751068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:25:06.751587Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:25:06.752669Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:25:06.752777Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:25:06.752812Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:25:06.754651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.116:2379"} {"level":"info","ts":"2026-10-02T15:25:06.754925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T15:25:06.755983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T15:25:06.756032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T15:25:09.772616Z","caller":"traceutil/trace.go:171","msg":"trace[366699866] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"385.827358ms","start":"2026-10-02T15:25:09.386763Z","end":"2026-10-02T15:25:09.772591Z","steps":["trace[366699866] 'process raft request' (duration: 385.632934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:09.773212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:09.386731Z","time spent":"385.971282ms","remote":"127.0.0.1:48464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:10.16646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.654356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-02T15:25:10.166564Z","caller":"traceutil/trace.go:171","msg":"trace[1905283659] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"387.931592ms","start":"2026-10-02T15:25:09.778616Z","end":"2026-10-02T15:25:10.166547Z","steps":["trace[1905283659] 'process raft request' (duration: 177.003319ms)","trace[1905283659] 'compare' (duration: 210.492663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:10.166613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:09.778584Z","time spent":"388.007995ms","remote":"127.0.0.1:48464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:10.478798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.566554ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-02T15:25:10.47893Z","caller":"traceutil/trace.go:171","msg":"trace[1208992419] linearizableReadLoop","detail":"{readStateIndex:105; appliedIndex:104; }","duration":"309.649181ms","start":"2026-10-02T15:25:10.169269Z","end":"2026-10-02T15:25:10.478919Z","steps":["trace[1208992419] 'read index received' (duration: 167.901732ms)","trace[1208992419] 'applied index is now lower than readState.Index' (duration: 141.746319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:10.478964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:10.145628Z","time spent":"333.331913ms","remote":"127.0.0.1:48222","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-02T15:25:10.479089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.817285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:25:10.479349Z","caller":"traceutil/trace.go:171","msg":"trace[133350499] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:0; response_revision:100; }","duration":"310.120802ms","start":"2026-10-02T15:25:10.169221Z","end":"2026-10-02T15:25:10.479342Z","steps":["trace[133350499] 'agreement among raft nodes before linearized reading' (duration: 309.783684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:10.479397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:10.169205Z","time spent":"310.183513ms","remote":"127.0.0.1:48464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" "} {"level":"warn","ts":"2026-10-02T15:25:10.47918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.075681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:25:10.479591Z","caller":"traceutil/trace.go:171","msg":"trace[1356642698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:100; }","duration":"198.536222ms","start":"2026-10-02T15:25:10.281048Z","end":"2026-10-02T15:25:10.479585Z","steps":["trace[1356642698] 'agreement among raft nodes before linearized reading' (duration: 198.04578ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:24.923846Z","caller":"traceutil/trace.go:171","msg":"trace[803644976] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"116.445006ms","start":"2026-10-02T15:25:24.807185Z","end":"2026-10-02T15:25:24.92363Z","steps":["trace[803644976] 'process raft request' (duration: 34.538421ms)","trace[803644976] 'compare' (duration: 81.754092ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:33.043356Z","caller":"traceutil/trace.go:171","msg":"trace[299723935] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"109.363764ms","start":"2026-10-02T15:25:32.933906Z","end":"2026-10-02T15:25:33.04327Z","steps":["trace[299723935] 'process raft request' (duration: 109.203811ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.043407Z","caller":"traceutil/trace.go:171","msg":"trace[889860076] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"109.122219ms","start":"2026-10-02T15:25:32.93423Z","end":"2026-10-02T15:25:33.043353Z","steps":["trace[889860076] 'process raft request' (duration: 108.926855ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.043669Z","caller":"traceutil/trace.go:171","msg":"trace[1966962005] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"109.389884ms","start":"2026-10-02T15:25:32.934247Z","end":"2026-10-02T15:25:33.043637Z","steps":["trace[1966962005] 'process raft request' (duration: 108.984896ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.043827Z","caller":"traceutil/trace.go:171","msg":"trace[351409426] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"110.089109ms","start":"2026-10-02T15:25:32.933713Z","end":"2026-10-02T15:25:33.043803Z","steps":["trace[351409426] 'process raft request' (duration: 94.850394ms)","trace[351409426] 'compare' (duration: 14.405768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:33.272445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.614738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:25:33.272635Z","caller":"traceutil/trace.go:171","msg":"trace[205741751] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"211.872289ms","start":"2026-10-02T15:25:33.060727Z","end":"2026-10-02T15:25:33.2726Z","steps":["trace[205741751] 'process raft request' (duration: 103.656779ms)","trace[205741751] 'compare' (duration: 107.446055ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:33.27289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.184617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-10-02T15:25:33.272967Z","caller":"traceutil/trace.go:171","msg":"trace[2028356295] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:449; }","duration":"165.292229ms","start":"2026-10-02T15:25:33.107654Z","end":"2026-10-02T15:25:33.272946Z","steps":["trace[2028356295] 'agreement among raft nodes before linearized reading' (duration: 165.148836ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.273495Z","caller":"traceutil/trace.go:171","msg":"trace[1271769167] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"160.621576ms","start":"2026-10-02T15:25:33.112857Z","end":"2026-10-02T15:25:33.273479Z","steps":["trace[1271769167] 'process raft request' (duration: 160.460163ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.275063Z","caller":"traceutil/trace.go:171","msg":"trace[1561501383] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"164.894281ms","start":"2026-10-02T15:25:33.107681Z","end":"2026-10-02T15:25:33.272575Z","steps":["trace[1561501383] 'read index received' (duration: 56.685381ms)","trace[1561501383] 'applied index is now lower than readState.Index' (duration: 108.20709ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:33.448732Z","caller":"traceutil/trace.go:171","msg":"trace[549754979] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"155.873981ms","start":"2026-10-02T15:25:33.292821Z","end":"2026-10-02T15:25:33.448695Z","steps":["trace[549754979] 'process raft request' (duration: 155.751799ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.448809Z","caller":"traceutil/trace.go:171","msg":"trace[1876411275] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"157.955263ms","start":"2026-10-02T15:25:33.290816Z","end":"2026-10-02T15:25:33.448771Z","steps":["trace[1876411275] 'process raft request' (duration: 89.16657ms)","trace[1876411275] 'compare' (duration: 68.297453ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:33.449023Z","caller":"traceutil/trace.go:171","msg":"trace[718000335] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"111.450355ms","start":"2026-10-02T15:25:33.337551Z","end":"2026-10-02T15:25:33.449001Z","steps":["trace[718000335] 'process raft request' (duration: 111.373113ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.449364Z","caller":"traceutil/trace.go:171","msg":"trace[1931766976] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"115.931684ms","start":"2026-10-02T15:25:33.333346Z","end":"2026-10-02T15:25:33.449277Z","steps":["trace[1931766976] 'process raft request' (duration: 115.481315ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.657693Z","caller":"traceutil/trace.go:171","msg":"trace[1898781601] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"145.735969ms","start":"2026-10-02T15:25:33.511906Z","end":"2026-10-02T15:25:33.657642Z","steps":["trace[1898781601] 'process raft request' (duration: 76.044098ms)","trace[1898781601] 'compare' (duration: 69.455036ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:33.660447Z","caller":"traceutil/trace.go:171","msg":"trace[337643841] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"145.773299ms","start":"2026-10-02T15:25:33.514645Z","end":"2026-10-02T15:25:33.660419Z","steps":["trace[337643841] 'process raft request' (duration: 145.466683ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:33.660686Z","caller":"traceutil/trace.go:171","msg":"trace[1251768983] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"144.858662ms","start":"2026-10-02T15:25:33.51577Z","end":"2026-10-02T15:25:33.660629Z","steps":["trace[1251768983] 'process raft request' (duration: 144.574156ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.698939Z","caller":"traceutil/trace.go:171","msg":"trace[1880160259] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"395.17243ms","start":"2026-10-02T15:25:47.303733Z","end":"2026-10-02T15:25:47.698905Z","steps":["trace[1880160259] 'process raft request' (duration: 394.989567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.699079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.303694Z","time spent":"395.321732ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.713323Z","caller":"traceutil/trace.go:171","msg":"trace[1458852869] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"406.790129ms","start":"2026-10-02T15:25:47.306515Z","end":"2026-10-02T15:25:47.713305Z","steps":["trace[1458852869] 'process raft request' (duration: 406.628696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.713401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.306483Z","time spent":"406.887901ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.713487Z","caller":"traceutil/trace.go:171","msg":"trace[1221854635] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"390.017767ms","start":"2026-10-02T15:25:47.323461Z","end":"2026-10-02T15:25:47.713479Z","steps":["trace[1221854635] 'process raft request' (duration: 389.799053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.713545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.323447Z","time spent":"390.074938ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.713574Z","caller":"traceutil/trace.go:171","msg":"trace[146917180] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"380.486845ms","start":"2026-10-02T15:25:47.333043Z","end":"2026-10-02T15:25:47.71353Z","steps":["trace[146917180] 'process raft request' (duration: 380.281222ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.713681Z","caller":"traceutil/trace.go:171","msg":"trace[829276477] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"397.334049ms","start":"2026-10-02T15:25:47.316337Z","end":"2026-10-02T15:25:47.713671Z","steps":["trace[829276477] 'process raft request' (duration: 396.882791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.713702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.333007Z","time spent":"380.641477ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:47.713731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.316315Z","time spent":"397.39413ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.713804Z","caller":"traceutil/trace.go:171","msg":"trace[544318791] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"378.445358ms","start":"2026-10-02T15:25:47.335353Z","end":"2026-10-02T15:25:47.713798Z","steps":["trace[544318791] 'process raft request' (duration: 378.116222ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.713816Z","caller":"traceutil/trace.go:171","msg":"trace[2104708824] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"373.106411ms","start":"2026-10-02T15:25:47.340701Z","end":"2026-10-02T15:25:47.713807Z","steps":["trace[2104708824] 'process raft request' (duration: 372.866577ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.713805Z","caller":"traceutil/trace.go:171","msg":"trace[1171634171] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"380.443164ms","start":"2026-10-02T15:25:47.33334Z","end":"2026-10-02T15:25:47.713783Z","steps":["trace[1171634171] 'process raft request' (duration: 380.077827ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.713854Z","caller":"traceutil/trace.go:171","msg":"trace[643553757] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"380.697789ms","start":"2026-10-02T15:25:47.333152Z","end":"2026-10-02T15:25:47.71385Z","steps":["trace[643553757] 'process raft request' (duration: 380.2156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.713851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.340674Z","time spent":"373.160233ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:47.71388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.333145Z","time spent":"380.722809ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:47.713944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.333328Z","time spent":"380.526156ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:25:47.71383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.335337Z","time spent":"378.481798ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.71413Z","caller":"traceutil/trace.go:171","msg":"trace[498838399] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"372.558612ms","start":"2026-10-02T15:25:47.341559Z","end":"2026-10-02T15:25:47.714118Z","steps":["trace[498838399] 'process raft request' (duration: 372.059463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.714183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.341506Z","time spent":"372.653684ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.715006Z","caller":"traceutil/trace.go:171","msg":"trace[241563756] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"269.533054ms","start":"2026-10-02T15:25:47.445458Z","end":"2026-10-02T15:25:47.714991Z","steps":["trace[241563756] 'read index received' (duration: 253.373632ms)","trace[241563756] 'applied index is now lower than readState.Index' (duration: 16.158652ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:47.715114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.643915ms","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-02T15:25:47.715146Z","caller":"traceutil/trace.go:171","msg":"trace[1828098109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"269.715616ms","start":"2026-10-02T15:25:47.445422Z","end":"2026-10-02T15:25:47.715138Z","steps":["trace[1828098109] 'agreement among raft nodes before linearized reading' (duration: 269.622785ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:47.715319Z","caller":"traceutil/trace.go:171","msg":"trace[1337568280] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"324.830941ms","start":"2026-10-02T15:25:47.390479Z","end":"2026-10-02T15:25:47.71531Z","steps":["trace[1337568280] 'process raft request' (duration: 323.195262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:47.715376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:47.390449Z","time spent":"324.897042ms","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:25:47.715485Z","caller":"traceutil/trace.go:171","msg":"trace[1102445753] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"182.37321ms","start":"2026-10-02T15:25:47.533104Z","end":"2026-10-02T15:25:47.715477Z","steps":["trace[1102445753] 'process raft request' (duration: 181.7773ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:53.12718Z","caller":"traceutil/trace.go:171","msg":"trace[1276547760] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"177.783809ms","start":"2026-10-02T15:25:52.94937Z","end":"2026-10-02T15:25:53.127154Z","steps":["trace[1276547760] 'process raft request' (duration: 177.632177ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:53.212934Z","caller":"traceutil/trace.go:171","msg":"trace[465010076] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"214.095294ms","start":"2026-10-02T15:25:52.998814Z","end":"2026-10-02T15:25:53.21291Z","steps":["trace[465010076] 'process raft request' (duration: 213.508253ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:25:53.569112Z","caller":"traceutil/trace.go:171","msg":"trace[961204440] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"289.947558ms","start":"2026-10-02T15:25:53.279139Z","end":"2026-10-02T15:25:53.569086Z","steps":["trace[961204440] 'process raft request' (duration: 287.684349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:53.569313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.221467ms","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-02T15:25:53.569495Z","caller":"traceutil/trace.go:171","msg":"trace[1385240823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"300.559472ms","start":"2026-10-02T15:25:53.268862Z","end":"2026-10-02T15:25:53.569421Z","steps":["trace[1385240823] 'range keys from in-memory index tree' (duration: 299.962082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:53.56961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:25:53.268832Z","time spent":"300.743666ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T15:25:53.867493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.801759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:25:53.867623Z","caller":"traceutil/trace.go:171","msg":"trace[183295591] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"287.15673ms","start":"2026-10-02T15:25:53.580409Z","end":"2026-10-02T15:25:53.867566Z","steps":["trace[183295591] 'read index received' (duration: 156.535235ms)","trace[183295591] 'applied index is now lower than readState.Index' (duration: 130.620115ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:53.867629Z","caller":"traceutil/trace.go:171","msg":"trace[2143254446] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"294.530268ms","start":"2026-10-02T15:25:53.573072Z","end":"2026-10-02T15:25:53.867602Z","steps":["trace[2143254446] 'process raft request' (duration: 163.545376ms)","trace[2143254446] 'compare' (duration: 130.673627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:25:53.867867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.074739ms","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-02T15:25:53.867984Z","caller":"traceutil/trace.go:171","msg":"trace[1275845254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"106.215002ms","start":"2026-10-02T15:25:53.761757Z","end":"2026-10-02T15:25:53.867972Z","steps":["trace[1275845254] 'agreement among raft nodes before linearized reading' (duration: 105.979328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:25:53.867797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.371524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:25:53.868583Z","caller":"traceutil/trace.go:171","msg":"trace[191319008] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:637; }","duration":"288.164998ms","start":"2026-10-02T15:25:53.580395Z","end":"2026-10-02T15:25:53.86856Z","steps":["trace[191319008] 'agreement among raft nodes before linearized reading' (duration: 287.310473ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:26:09.140497Z","caller":"traceutil/trace.go:171","msg":"trace[1961587513] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"108.955058ms","start":"2026-10-02T15:26:09.031521Z","end":"2026-10-02T15:26:09.140477Z","steps":["trace[1961587513] 'read index received' (duration: 108.806175ms)","trace[1961587513] 'applied index is now lower than readState.Index' (duration: 148.023µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:26:09.140698Z","caller":"traceutil/trace.go:171","msg":"trace[747121283] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"300.922109ms","start":"2026-10-02T15:26:08.839753Z","end":"2026-10-02T15:26:09.140675Z","steps":["trace[747121283] 'process raft request' (duration: 300.590144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:26:09.140774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.240992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nkcg6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-02T15:26:09.140809Z","caller":"traceutil/trace.go:171","msg":"trace[1375227958] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nkcg6; range_end:; response_count:1; response_revision:801; }","duration":"109.327024ms","start":"2026-10-02T15:26:09.031471Z","end":"2026-10-02T15:26:09.140798Z","steps":["trace[1375227958] 'agreement among raft nodes before linearized reading' (duration: 109.158561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:26:09.140832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:26:08.839741Z","time spent":"301.02211ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:26:42.635179Z","caller":"traceutil/trace.go:171","msg":"trace[1778614184] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"148.520371ms","start":"2026-10-02T15:26:42.486639Z","end":"2026-10-02T15:26:42.635159Z","steps":["trace[1778614184] 'process raft request' (duration: 148.406639ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:26:49.493572Z","caller":"traceutil/trace.go:171","msg":"trace[850804764] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"131.772699ms","start":"2026-10-02T15:26:49.361768Z","end":"2026-10-02T15:26:49.49354Z","steps":["trace[850804764] 'process raft request' (duration: 131.596326ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:26:49.57378Z","caller":"traceutil/trace.go:171","msg":"trace[1682076733] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"174.944909ms","start":"2026-10-02T15:26:49.398803Z","end":"2026-10-02T15:26:49.573748Z","steps":["trace[1682076733] 'process raft request' (duration: 157.069484ms)","trace[1682076733] 'compare' (duration: 17.778994ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:27:06.533177Z","caller":"traceutil/trace.go:171","msg":"trace[1851648977] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"173.046465ms","start":"2026-10-02T15:27:06.360089Z","end":"2026-10-02T15:27:06.533136Z","steps":["trace[1851648977] 'process raft request' (duration: 172.826862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:27:30.017367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.838348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-02T15:27:30.017569Z","caller":"traceutil/trace.go:171","msg":"trace[424179528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"312.111001ms","start":"2026-10-02T15:27:29.70544Z","end":"2026-10-02T15:27:30.017551Z","steps":["trace[424179528] 'range keys from in-memory index tree' (duration: 311.663076ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:27:30.017626Z","caller":"traceutil/trace.go:171","msg":"trace[123546426] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"304.634524ms","start":"2026-10-02T15:27:29.712969Z","end":"2026-10-02T15:27:30.017604Z","steps":["trace[123546426] 'process raft request' (duration: 294.854868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:27:30.017766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:27:29.712954Z","time spent":"304.739565ms","remote":"127.0.0.1:48386","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-02T15:27:30.0178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:27:29.705421Z","time spent":"312.365225ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T15:27:38.392657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.543204ms","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-02T15:27:38.392741Z","caller":"traceutil/trace.go:171","msg":"trace[2007258635] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"103.728636ms","start":"2026-10-02T15:27:38.288991Z","end":"2026-10-02T15:27:38.392719Z","steps":["trace[2007258635] 'range keys from in-memory index tree' (duration: 103.27264ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:01.715848Z","caller":"traceutil/trace.go:171","msg":"trace[1566213099] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"155.125888ms","start":"2026-10-02T15:28:01.560692Z","end":"2026-10-02T15:28:01.715817Z","steps":["trace[1566213099] 'process raft request' (duration: 154.886106ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:01.955362Z","caller":"traceutil/trace.go:171","msg":"trace[717872671] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"231.816969ms","start":"2026-10-02T15:28:01.723446Z","end":"2026-10-02T15:28:01.955263Z","steps":["trace[717872671] 'process raft request' (duration: 174.728409ms)","trace[717872671] 'compare' (duration: 56.751236ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:28:02.023182Z","caller":"traceutil/trace.go:171","msg":"trace[2120552689] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"297.760577ms","start":"2026-10-02T15:28:01.725387Z","end":"2026-10-02T15:28:02.023148Z","steps":["trace[2120552689] 'process raft request' (duration: 297.572705ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:02.023568Z","caller":"traceutil/trace.go:171","msg":"trace[577234189] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2206; }","duration":"295.140918ms","start":"2026-10-02T15:28:01.728402Z","end":"2026-10-02T15:28:02.023543Z","steps":["trace[577234189] 'read index received' (duration: 169.687873ms)","trace[577234189] 'applied index is now lower than readState.Index' (duration: 125.451775ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:28:02.023604Z","caller":"traceutil/trace.go:171","msg":"trace[706214952] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"196.165109ms","start":"2026-10-02T15:28:01.827421Z","end":"2026-10-02T15:28:02.023586Z","steps":["trace[706214952] 'process raft request' (duration: 196.021037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:28:02.023753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.338969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-02T15:28:02.023758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.346801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-02T15:28:02.023794Z","caller":"traceutil/trace.go:171","msg":"trace[1476901848] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2140; }","duration":"129.43405ms","start":"2026-10-02T15:28:01.894348Z","end":"2026-10-02T15:28:02.023782Z","steps":["trace[1476901848] 'agreement among raft nodes before linearized reading' (duration: 129.335929ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:02.023805Z","caller":"traceutil/trace.go:171","msg":"trace[1687885506] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2140; }","duration":"295.429921ms","start":"2026-10-02T15:28:01.728362Z","end":"2026-10-02T15:28:02.023792Z","steps":["trace[1687885506] 'agreement among raft nodes before linearized reading' (duration: 295.271139ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:02.023849Z","caller":"traceutil/trace.go:171","msg":"trace[1679123081] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"207.157322ms","start":"2026-10-02T15:28:01.81659Z","end":"2026-10-02T15:28:02.023747Z","steps":["trace[1679123081] 'process raft request' (duration: 206.489684ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:02.134375Z","caller":"traceutil/trace.go:171","msg":"trace[1866013904] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"105.176377ms","start":"2026-10-02T15:28:02.029174Z","end":"2026-10-02T15:28:02.134351Z","steps":["trace[1866013904] 'process raft request' (duration: 86.755821ms)","trace[1866013904] 'compare' (duration: 18.252534ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:28:02.134742Z","caller":"traceutil/trace.go:171","msg":"trace[1428714523] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"103.60702ms","start":"2026-10-02T15:28:02.031103Z","end":"2026-10-02T15:28:02.13471Z","steps":["trace[1428714523] 'process raft request' (duration: 103.198576ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:02.312726Z","caller":"traceutil/trace.go:171","msg":"trace[2079416208] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"117.051451ms","start":"2026-10-02T15:28:02.195642Z","end":"2026-10-02T15:28:02.312693Z","steps":["trace[2079416208] 'process raft request' (duration: 116.834558ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:49.390275Z","caller":"traceutil/trace.go:171","msg":"trace[293103118] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"100.215254ms","start":"2026-10-02T15:28:49.29002Z","end":"2026-10-02T15:28:49.390235Z","steps":["trace[293103118] 'process raft request' (duration: 100.001728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:28:59.007481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:28:59.007566Z","caller":"traceutil/trace.go:171","msg":"trace[1745234256] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2633; }","duration":"117.472426ms","start":"2026-10-02T15:28:58.890074Z","end":"2026-10-02T15:28:59.007546Z","steps":["trace[1745234256] 'count revisions from in-memory index tree' (duration: 117.2383ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:28:59.245446Z","caller":"traceutil/trace.go:171","msg":"trace[1663279139] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"102.017482ms","start":"2026-10-02T15:28:59.143403Z","end":"2026-10-02T15:28:59.24542Z","steps":["trace[1663279139] 'process raft request' (duration: 101.858437ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:32:01.367405Z","caller":"traceutil/trace.go:171","msg":"trace[439297331] linearizableReadLoop","detail":"{readStateIndex:4774; appliedIndex:4773; }","duration":"162.073961ms","start":"2026-10-02T15:32:01.205306Z","end":"2026-10-02T15:32:01.36738Z","steps":["trace[439297331] 'read index received' (duration: 141.217188ms)","trace[439297331] 'applied index is now lower than readState.Index' (duration: 20.855913ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:32:01.367727Z","caller":"traceutil/trace.go:171","msg":"trace[457187831] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"202.315764ms","start":"2026-10-02T15:32:01.165396Z","end":"2026-10-02T15:32:01.367712Z","steps":["trace[457187831] 'process raft request' (duration: 181.175066ms)","trace[457187831] 'compare' (duration: 20.718892ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:32:01.367948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.63168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-02T15:32:01.368044Z","caller":"traceutil/trace.go:171","msg":"trace[994119574] range","detail":"{range_begin:/registry/masterleases/162.253.55.116; range_end:; response_count:1; response_revision:4583; }","duration":"162.744382ms","start":"2026-10-02T15:32:01.205256Z","end":"2026-10-02T15:32:01.368Z","steps":["trace[994119574] 'agreement among raft nodes before linearized reading' (duration: 162.6173ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:32:01.524685Z","caller":"traceutil/trace.go:171","msg":"trace[1539553720] linearizableReadLoop","detail":"{readStateIndex:4776; appliedIndex:4774; }","duration":"136.308558ms","start":"2026-10-02T15:32:01.388357Z","end":"2026-10-02T15:32:01.524665Z","steps":["trace[1539553720] 'read index received' (duration: 80.69573ms)","trace[1539553720] 'applied index is now lower than readState.Index' (duration: 55.611928ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:32:01.524777Z","caller":"traceutil/trace.go:171","msg":"trace[2111725684] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"153.090926ms","start":"2026-10-02T15:32:01.371622Z","end":"2026-10-02T15:32:01.524713Z","steps":["trace[2111725684] 'process raft request' (duration: 152.945654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:01.524944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.588102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-788534bc-2b44-4f70-be6f-c8ffe27529a3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-02T15:32:01.525193Z","caller":"traceutil/trace.go:171","msg":"trace[482583621] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-788534bc-2b44-4f70-be6f-c8ffe27529a3; range_end:; response_count:1; response_revision:4584; }","duration":"136.859967ms","start":"2026-10-02T15:32:01.388289Z","end":"2026-10-02T15:32:01.525149Z","steps":["trace[482583621] 'agreement among raft nodes before linearized reading' (duration: 136.482081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:01.525198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.998543ms","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-02T15:32:01.525254Z","caller":"traceutil/trace.go:171","msg":"trace[989562749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4584; }","duration":"101.098406ms","start":"2026-10-02T15:32:01.424141Z","end":"2026-10-02T15:32:01.525239Z","steps":["trace[989562749] 'agreement among raft nodes before linearized reading' (duration: 100.868012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:05.98266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.870027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T15:32:05.98277Z","caller":"traceutil/trace.go:171","msg":"trace[984431385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4689; }","duration":"148.068221ms","start":"2026-10-02T15:32:05.834678Z","end":"2026-10-02T15:32:05.982746Z","steps":["trace[984431385] 'count revisions from in-memory index tree' (duration: 147.777806ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:32:10.718714Z","caller":"traceutil/trace.go:171","msg":"trace[319454962] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"131.487ms","start":"2026-10-02T15:32:10.58718Z","end":"2026-10-02T15:32:10.718667Z","steps":["trace[319454962] 'process raft request' (duration: 131.361328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:14.951698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.847752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:32:14.95179Z","caller":"traceutil/trace.go:171","msg":"trace[900054752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4814; }","duration":"147.992005ms","start":"2026-10-02T15:32:14.803781Z","end":"2026-10-02T15:32:14.951773Z","steps":["trace[900054752] 'range keys from in-memory index tree' (duration: 147.71195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:14.95179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.823343ms","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-02T15:32:14.951884Z","caller":"traceutil/trace.go:171","msg":"trace[1457067247] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4814; }","duration":"204.986396ms","start":"2026-10-02T15:32:14.746871Z","end":"2026-10-02T15:32:14.951858Z","steps":["trace[1457067247] 'range keys from in-memory index tree' (duration: 204.60944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:14.95191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.822322ms","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-02T15:32:14.951999Z","caller":"traceutil/trace.go:171","msg":"trace[785428790] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4814; }","duration":"140.962595ms","start":"2026-10-02T15:32:14.811013Z","end":"2026-10-02T15:32:14.951975Z","steps":["trace[785428790] 'range keys from in-memory index tree' (duration: 140.6136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:15.38186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.176303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T15:32:15.381959Z","caller":"traceutil/trace.go:171","msg":"trace[911542442] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:4823; }","duration":"146.318314ms","start":"2026-10-02T15:32:15.235607Z","end":"2026-10-02T15:32:15.381925Z","steps":["trace[911542442] 'count revisions from in-memory index tree' (duration: 146.098962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:32:50.805203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.553965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61996"} {"level":"info","ts":"2026-10-02T15:32:50.805299Z","caller":"traceutil/trace.go:171","msg":"trace[160624524] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5197; }","duration":"117.656897ms","start":"2026-10-02T15:32:50.687623Z","end":"2026-10-02T15:32:50.805279Z","steps":["trace[160624524] 'range keys from in-memory index tree' (duration: 117.301572ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:33:04.476627Z","caller":"traceutil/trace.go:171","msg":"trace[1281658775] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"122.115062ms","start":"2026-10-02T15:33:04.35448Z","end":"2026-10-02T15:33:04.476595Z","steps":["trace[1281658775] 'process raft request' (duration: 121.989861ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:33:37.914336Z","caller":"traceutil/trace.go:171","msg":"trace[1660594438] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"104.051887ms","start":"2026-10-02T15:33:37.810267Z","end":"2026-10-02T15:33:37.914319Z","steps":["trace[1660594438] 'process raft request' (duration: 103.707372ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:33:37.914321Z","caller":"traceutil/trace.go:171","msg":"trace[1938085705] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"106.750055ms","start":"2026-10-02T15:33:37.807541Z","end":"2026-10-02T15:33:37.914291Z","steps":["trace[1938085705] 'process raft request' (duration: 78.01292ms)","trace[1938085705] 'compare' (duration: 28.313399ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:35:06.783321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3164} {"level":"info","ts":"2026-10-02T15:35:06.923428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3164,"took":"138.688599ms","hash":1955089603,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":31252480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T15:35:06.923481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1955089603,"revision":3164,"compact-revision":-1} {"level":"info","ts":"2026-10-02T15:38:03.849201Z","caller":"traceutil/trace.go:171","msg":"trace[1564369844] linearizableReadLoop","detail":"{readStateIndex:7980; appliedIndex:7978; }","duration":"104.574889ms","start":"2026-10-02T15:38:03.744599Z","end":"2026-10-02T15:38:03.849174Z","steps":["trace[1564369844] 'read index received' (duration: 21.648435ms)","trace[1564369844] 'applied index is now lower than readState.Index' (duration: 82.925064ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:03.84934Z","caller":"traceutil/trace.go:171","msg":"trace[934574105] transaction","detail":"{read_only:false; response_revision:7630; number_of_response:1; }","duration":"111.588568ms","start":"2026-10-02T15:38:03.73774Z","end":"2026-10-02T15:38:03.849329Z","steps":["trace[934574105] 'process raft request' (duration: 103.11448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:03.851202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.979963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T15:38:03.851278Z","caller":"traceutil/trace.go:171","msg":"trace[1294203417] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:7630; }","duration":"106.702511ms","start":"2026-10-02T15:38:03.74456Z","end":"2026-10-02T15:38:03.851262Z","steps":["trace[1294203417] 'agreement among raft nodes before linearized reading' (duration: 104.971003ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.656985Z","caller":"traceutil/trace.go:171","msg":"trace[198313782] linearizableReadLoop","detail":"{readStateIndex:7986; appliedIndex:7985; }","duration":"293.8383ms","start":"2026-10-02T15:38:05.363123Z","end":"2026-10-02T15:38:05.656961Z","steps":["trace[198313782] 'read index received' (duration: 293.586522ms)","trace[198313782] 'applied index is now lower than readState.Index' (duration: 251.008µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:05.657065Z","caller":"traceutil/trace.go:171","msg":"trace[1368948299] transaction","detail":"{read_only:false; response_revision:7636; number_of_response:1; }","duration":"295.866439ms","start":"2026-10-02T15:38:05.361175Z","end":"2026-10-02T15:38:05.657042Z","steps":["trace[1368948299] 'process raft request' (duration: 295.646121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:05.657319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.165581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-02T15:38:05.657396Z","caller":"traceutil/trace.go:171","msg":"trace[892086485] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7636; }","duration":"294.276315ms","start":"2026-10-02T15:38:05.363099Z","end":"2026-10-02T15:38:05.657375Z","steps":["trace[892086485] 'agreement among raft nodes before linearized reading' (duration: 294.006486ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.933047Z","caller":"traceutil/trace.go:171","msg":"trace[1106859535] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"270.810072ms","start":"2026-10-02T15:38:05.662207Z","end":"2026-10-02T15:38:05.933017Z","steps":["trace[1106859535] 'process raft request' (duration: 270.665257ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.957547Z","caller":"traceutil/trace.go:171","msg":"trace[1251707611] transaction","detail":"{read_only:false; response_revision:7638; number_of_response:1; }","duration":"294.637227ms","start":"2026-10-02T15:38:05.662872Z","end":"2026-10-02T15:38:05.957509Z","steps":["trace[1251707611] 'process raft request' (duration: 294.364948ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.957771Z","caller":"traceutil/trace.go:171","msg":"trace[1350493037] linearizableReadLoop","detail":"{readStateIndex:7990; appliedIndex:7987; }","duration":"187.860209ms","start":"2026-10-02T15:38:05.769892Z","end":"2026-10-02T15:38:05.957752Z","steps":["trace[1350493037] 'read index received' (duration: 163.232587ms)","trace[1350493037] 'applied index is now lower than readState.Index' (duration: 24.626672ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:05.95783Z","caller":"traceutil/trace.go:171","msg":"trace[571749968] transaction","detail":"{read_only:false; response_revision:7639; number_of_response:1; }","duration":"294.841794ms","start":"2026-10-02T15:38:05.662966Z","end":"2026-10-02T15:38:05.957808Z","steps":["trace[571749968] 'process raft request' (duration: 294.41984ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.95785Z","caller":"traceutil/trace.go:171","msg":"trace[934640872] transaction","detail":"{read_only:false; response_revision:7640; number_of_response:1; }","duration":"292.63601ms","start":"2026-10-02T15:38:05.665193Z","end":"2026-10-02T15:38:05.957829Z","steps":["trace[934640872] 'process raft request' (duration: 292.472314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:05.957995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.076556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-02T15:38:05.957989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.365121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:38:05.958031Z","caller":"traceutil/trace.go:171","msg":"trace[279528575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7640; }","duration":"188.177059ms","start":"2026-10-02T15:38:05.769843Z","end":"2026-10-02T15:38:05.95802Z","steps":["trace[279528575] 'agreement among raft nodes before linearized reading' (duration: 188.020254ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:05.95805Z","caller":"traceutil/trace.go:171","msg":"trace[630804800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7640; }","duration":"155.472764ms","start":"2026-10-02T15:38:05.802561Z","end":"2026-10-02T15:38:05.958034Z","steps":["trace[630804800] 'agreement among raft nodes before linearized reading' (duration: 155.34451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:05.958068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.076791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-02T15:38:05.958123Z","caller":"traceutil/trace.go:171","msg":"trace[2070766081] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7640; }","duration":"105.156654ms","start":"2026-10-02T15:38:05.85295Z","end":"2026-10-02T15:38:05.958107Z","steps":["trace[2070766081] 'agreement among raft nodes before linearized reading' (duration: 104.953827ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:38:06.090307Z","caller":"traceutil/trace.go:171","msg":"trace[691282783] transaction","detail":"{read_only:false; response_revision:7642; number_of_response:1; }","duration":"125.828866ms","start":"2026-10-02T15:38:05.964451Z","end":"2026-10-02T15:38:06.09028Z","steps":["trace[691282783] 'process raft request' (duration: 101.372012ms)","trace[691282783] 'compare' (duration: 24.233097ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:06.090485Z","caller":"traceutil/trace.go:171","msg":"trace[1307308005] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"124.323885ms","start":"2026-10-02T15:38:05.966118Z","end":"2026-10-02T15:38:06.090442Z","steps":["trace[1307308005] 'process raft request' (duration: 124.107857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:06.764656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.401905ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T15:38:06.76475Z","caller":"traceutil/trace.go:171","msg":"trace[1732617772] linearizableReadLoop","detail":"{readStateIndex:7997; appliedIndex:7996; }","duration":"191.820368ms","start":"2026-10-02T15:38:06.572911Z","end":"2026-10-02T15:38:06.764732Z","steps":["trace[1732617772] 'read index received' (duration: 31.481µs)","trace[1732617772] 'applied index is now lower than readState.Index' (duration: 191.787137ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:38:06.76504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.112187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:38:06.765087Z","caller":"traceutil/trace.go:171","msg":"trace[1852540239] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7646; }","duration":"192.216691ms","start":"2026-10-02T15:38:06.572854Z","end":"2026-10-02T15:38:06.76507Z","steps":["trace[1852540239] 'agreement among raft nodes before linearized reading' (duration: 191.923531ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:40:06.789487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6244} {"level":"info","ts":"2026-10-02T15:40:06.885975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6244,"took":"95.279104ms","hash":942285738,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":18563072,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T15:40:06.886063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":942285738,"revision":6244,"compact-revision":3164}