{"level":"warn","ts":"2026-07-29T15:43:21.362891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T15:43:21.362998Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-07-29T15:43:21.363078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T15:43:21.363087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-07-29T15:43:21.363107Z","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-07-29T15:43:21.363813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-07-29T15:43:21.363915Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-07-29T15:43:21.367064Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.896976ms"} {"level":"info","ts":"2026-07-29T15:43:21.372516Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-07-29T15:43:21.372602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T15:43:21.372621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-07-29T15:43:21.37264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T15:43:21.372651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-07-29T15:43:21.372694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-07-29T15:43:21.383354Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T15:43:21.386041Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T15:43:21.387321Z","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-07-29T15:43:21.390409Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T15:43:21.39069Z","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-07-29T15:43:21.390808Z","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-07-29T15:43:21.390829Z","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-07-29T15:43:21.390771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T15:43:21.391591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-07-29T15:43:21.392305Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-07-29T15:43:21.395589Z","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-07-29T15:43:21.395719Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-07-29T15:43:21.395801Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-07-29T15:43:21.396059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T15:43:21.396145Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T15:43:21.873699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T15:43:21.873767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T15:43:21.873808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-07-29T15:43:21.873829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-07-29T15:43:21.873842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-07-29T15:43:21.873858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-07-29T15:43:21.873895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-07-29T15:43:21.875025Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:43:21.87593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T15:43:21.875936Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T15:43:21.875977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T15:43:21.876427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T15:43:21.876502Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T15:43:21.879932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-07-29T15:43:21.880273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T15:43:21.894327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:43:21.895257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:43:21.895472Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T15:43:39.247765Z","caller":"traceutil/trace.go:171","msg":"trace[1433385573] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"140.295918ms","start":"2026-07-29T15:43:39.107414Z","end":"2026-07-29T15:43:39.24771Z","steps":["trace[1433385573] 'process raft request' (duration: 140.126073ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.096542Z","caller":"traceutil/trace.go:171","msg":"trace[1946666039] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"104.394914ms","start":"2026-07-29T15:43:58.992126Z","end":"2026-07-29T15:43:59.096521Z","steps":["trace[1946666039] 'process raft request' (duration: 104.2622ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.130852Z","caller":"traceutil/trace.go:171","msg":"trace[1150414056] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"118.735943ms","start":"2026-07-29T15:43:59.012075Z","end":"2026-07-29T15:43:59.130811Z","steps":["trace[1150414056] 'process raft request' (duration: 118.463965ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.1311Z","caller":"traceutil/trace.go:171","msg":"trace[584744683] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"103.054004ms","start":"2026-07-29T15:43:59.028029Z","end":"2026-07-29T15:43:59.131083Z","steps":["trace[584744683] 'process raft request' (duration: 102.768776ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.131098Z","caller":"traceutil/trace.go:171","msg":"trace[1889841102] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"103.158137ms","start":"2026-07-29T15:43:59.027882Z","end":"2026-07-29T15:43:59.13104Z","steps":["trace[1889841102] 'process raft request' (duration: 102.873568ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.131204Z","caller":"traceutil/trace.go:171","msg":"trace[1143935262] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"103.096135ms","start":"2026-07-29T15:43:59.028099Z","end":"2026-07-29T15:43:59.131195Z","steps":["trace[1143935262] 'process raft request' (duration: 102.753905ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.13115Z","caller":"traceutil/trace.go:171","msg":"trace[1702397248] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"118.832156ms","start":"2026-07-29T15:43:59.012161Z","end":"2026-07-29T15:43:59.130993Z","steps":["trace[1702397248] 'process raft request' (duration: 118.501517ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.130925Z","caller":"traceutil/trace.go:171","msg":"trace[54174911] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"106.501757ms","start":"2026-07-29T15:43:59.024399Z","end":"2026-07-29T15:43:59.130901Z","steps":["trace[54174911] 'process raft request' (duration: 106.312561ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:43:59.13131Z","caller":"traceutil/trace.go:171","msg":"trace[990171659] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"100.723464ms","start":"2026-07-29T15:43:59.030522Z","end":"2026-07-29T15:43:59.131245Z","steps":["trace[990171659] 'process raft request' (duration: 100.444256ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:44:05.145626Z","caller":"traceutil/trace.go:171","msg":"trace[1317258858] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"159.442322ms","start":"2026-07-29T15:44:04.986142Z","end":"2026-07-29T15:44:05.145585Z","steps":["trace[1317258858] 'process raft request' (duration: 159.263206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:44:05.349997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.078382ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-29T15:44:05.350107Z","caller":"traceutil/trace.go:171","msg":"trace[853276920] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"145.094812ms","start":"2026-07-29T15:44:05.204997Z","end":"2026-07-29T15:44:05.350092Z","steps":["trace[853276920] 'read index received' (duration: 15.71731ms)","trace[853276920] 'applied index is now lower than readState.Index' (duration: 129.375922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:44:05.350874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.838365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zpbhv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-29T15:44:05.350965Z","caller":"traceutil/trace.go:171","msg":"trace[1289189192] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zpbhv; range_end:; response_count:1; response_revision:587; }","duration":"145.969189ms","start":"2026-07-29T15:44:05.204973Z","end":"2026-07-29T15:44:05.350942Z","steps":["trace[1289189192] 'agreement among raft nodes before linearized reading' (duration: 145.68513ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:44:05.616125Z","caller":"traceutil/trace.go:171","msg":"trace[1105274541] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"251.169496ms","start":"2026-07-29T15:44:05.364869Z","end":"2026-07-29T15:44:05.616038Z","steps":["trace[1105274541] 'read index received' (duration: 176.1379ms)","trace[1105274541] 'applied index is now lower than readState.Index' (duration: 75.030496ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:44:05.616337Z","caller":"traceutil/trace.go:171","msg":"trace[1305471857] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"251.544957ms","start":"2026-07-29T15:44:05.36461Z","end":"2026-07-29T15:44:05.616155Z","steps":["trace[1305471857] 'process raft request' (duration: 176.45515ms)","trace[1305471857] 'compare' (duration: 74.809879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:44:05.616386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.484796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-29T15:44:05.6167Z","caller":"traceutil/trace.go:171","msg":"trace[877185995] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:589; }","duration":"251.834176ms","start":"2026-07-29T15:44:05.364821Z","end":"2026-07-29T15:44:05.616655Z","steps":["trace[877185995] 'agreement among raft nodes before linearized reading' (duration: 251.407963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:44:06.08134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.288305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:44:06.081479Z","caller":"traceutil/trace.go:171","msg":"trace[2142412562] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"462.442528ms","start":"2026-07-29T15:44:05.619019Z","end":"2026-07-29T15:44:06.081461Z","steps":["trace[2142412562] 'read index received' (duration: 285.416311ms)","trace[2142412562] 'applied index is now lower than readState.Index' (duration: 177.024567ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:44:06.081556Z","caller":"traceutil/trace.go:171","msg":"trace[645985970] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"528.040522ms","start":"2026-07-29T15:44:05.553458Z","end":"2026-07-29T15:44:06.081499Z","steps":["trace[645985970] 'process raft request' (duration: 351.154868ms)","trace[645985970] 'compare' (duration: 175.691648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:44:06.081752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:44:05.553417Z","time spent":"528.229367ms","remote":"127.0.0.1:52998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:44:06.081807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.758908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-29T15:44:06.081839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.161382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-29T15:44:06.08186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.323493ms","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-07-29T15:44:06.08188Z","caller":"traceutil/trace.go:171","msg":"trace[1088808601] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:590; }","duration":"462.85361ms","start":"2026-07-29T15:44:05.619007Z","end":"2026-07-29T15:44:06.081861Z","steps":["trace[1088808601] 'agreement among raft nodes before linearized reading' (duration: 462.585612ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:44:06.081884Z","caller":"traceutil/trace.go:171","msg":"trace[1001431441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"436.241755ms","start":"2026-07-29T15:44:05.64563Z","end":"2026-07-29T15:44:06.081872Z","steps":["trace[1001431441] 'agreement among raft nodes before linearized reading' (duration: 436.07668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:44:06.081919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:44:05.618941Z","time spent":"462.970714ms","remote":"127.0.0.1:52938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-29T15:44:06.081921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:44:05.645605Z","time spent":"436.308267ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-29T15:44:06.081917Z","caller":"traceutil/trace.go:171","msg":"trace[462328595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"357.406715ms","start":"2026-07-29T15:44:05.72449Z","end":"2026-07-29T15:44:06.081896Z","steps":["trace[462328595] 'agreement among raft nodes before linearized reading' (duration: 357.243881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:44:06.081964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:44:05.724471Z","time spent":"357.484427ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T15:44:06.081988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.138122ms","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-07-29T15:44:06.082087Z","caller":"traceutil/trace.go:171","msg":"trace[1974736861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"425.278486ms","start":"2026-07-29T15:44:05.656784Z","end":"2026-07-29T15:44:06.082062Z","steps":["trace[1974736861] 'agreement among raft nodes before linearized reading' (duration: 425.030709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:44:06.082146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:44:05.656763Z","time spent":"425.370359ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-29T15:45:31.969197Z","caller":"traceutil/trace.go:171","msg":"trace[893414791] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"278.226347ms","start":"2026-07-29T15:45:31.690943Z","end":"2026-07-29T15:45:31.969169Z","steps":["trace[893414791] 'read index received' (duration: 278.097883ms)","trace[893414791] 'applied index is now lower than readState.Index' (duration: 127.364µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:45:31.969291Z","caller":"traceutil/trace.go:171","msg":"trace[1764269598] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"280.83403ms","start":"2026-07-29T15:45:31.688413Z","end":"2026-07-29T15:45:31.969247Z","steps":["trace[1764269598] 'process raft request' (duration: 280.593543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:45:31.969465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.485534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:45:31.969512Z","caller":"traceutil/trace.go:171","msg":"trace[1843766611] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1818; }","duration":"278.572687ms","start":"2026-07-29T15:45:31.690928Z","end":"2026-07-29T15:45:31.969501Z","steps":["trace[1843766611] 'agreement among raft nodes before linearized reading' (duration: 278.453964ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:32.104792Z","caller":"traceutil/trace.go:171","msg":"trace[552211447] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"410.197664ms","start":"2026-07-29T15:45:31.694567Z","end":"2026-07-29T15:45:32.104765Z","steps":["trace[552211447] 'process raft request' (duration: 409.981258ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:32.104953Z","caller":"traceutil/trace.go:171","msg":"trace[26403785] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1888; }","duration":"135.60761ms","start":"2026-07-29T15:45:31.969317Z","end":"2026-07-29T15:45:32.104924Z","steps":["trace[26403785] 'read index received' (duration: 135.235729ms)","trace[26403785] 'applied index is now lower than readState.Index' (duration: 369.281µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:45:32.104978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:45:31.694514Z","time spent":"410.361079ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:45:32.105073Z","caller":"traceutil/trace.go:171","msg":"trace[1221683476] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"232.166599ms","start":"2026-07-29T15:45:31.872869Z","end":"2026-07-29T15:45:32.105036Z","steps":["trace[1221683476] 'process raft request' (duration: 231.83601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:45:32.105289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.43028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-29T15:45:32.105345Z","caller":"traceutil/trace.go:171","msg":"trace[367133830] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1820; }","duration":"241.534063ms","start":"2026-07-29T15:45:31.863796Z","end":"2026-07-29T15:45:32.10533Z","steps":["trace[367133830] 'agreement among raft nodes before linearized reading' (duration: 241.247215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:45:32.105694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.255796ms","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-07-29T15:45:32.10576Z","caller":"traceutil/trace.go:171","msg":"trace[291953573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1820; }","duration":"290.360289ms","start":"2026-07-29T15:45:31.815384Z","end":"2026-07-29T15:45:32.105744Z","steps":["trace[291953573] 'agreement among raft nodes before linearized reading' (duration: 290.176994ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:32.117183Z","caller":"traceutil/trace.go:171","msg":"trace[705406600] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"135.855447ms","start":"2026-07-29T15:45:31.981312Z","end":"2026-07-29T15:45:32.117168Z","steps":["trace[705406600] 'process raft request' (duration: 135.724253ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:32.380562Z","caller":"traceutil/trace.go:171","msg":"trace[1178496246] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"144.982974ms","start":"2026-07-29T15:45:32.235557Z","end":"2026-07-29T15:45:32.38054Z","steps":["trace[1178496246] 'read index received' (duration: 144.757058ms)","trace[1178496246] 'applied index is now lower than readState.Index' (duration: 225.176µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:45:32.38067Z","caller":"traceutil/trace.go:171","msg":"trace[1763725747] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"243.839398ms","start":"2026-07-29T15:45:32.1368Z","end":"2026-07-29T15:45:32.38064Z","steps":["trace[1763725747] 'process raft request' (duration: 243.585511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:45:32.380848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.239361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-29T15:45:32.380954Z","caller":"traceutil/trace.go:171","msg":"trace[1881970876] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"145.389225ms","start":"2026-07-29T15:45:32.235544Z","end":"2026-07-29T15:45:32.380933Z","steps":["trace[1881970876] 'agreement among raft nodes before linearized reading' (duration: 145.076937ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:40.837203Z","caller":"traceutil/trace.go:171","msg":"trace[1784022988] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"163.952329ms","start":"2026-07-29T15:45:40.673223Z","end":"2026-07-29T15:45:40.837175Z","steps":["trace[1784022988] 'process raft request' (duration: 163.904437ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:40.837303Z","caller":"traceutil/trace.go:171","msg":"trace[10688010] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"164.577406ms","start":"2026-07-29T15:45:40.672689Z","end":"2026-07-29T15:45:40.837266Z","steps":["trace[10688010] 'process raft request' (duration: 164.393351ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:45:40.837309Z","caller":"traceutil/trace.go:171","msg":"trace[1005147561] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"164.951376ms","start":"2026-07-29T15:45:40.672289Z","end":"2026-07-29T15:45:40.83724Z","steps":["trace[1005147561] 'process raft request' (duration: 128.277404ms)","trace[1005147561] 'compare' (duration: 36.354983ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:45:44.842183Z","caller":"traceutil/trace.go:171","msg":"trace[363903354] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"127.338827ms","start":"2026-07-29T15:45:44.714816Z","end":"2026-07-29T15:45:44.842155Z","steps":["trace[363903354] 'process raft request' (duration: 127.153931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:17.230287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.403424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:46:17.230384Z","caller":"traceutil/trace.go:171","msg":"trace[600468636] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"159.387959ms","start":"2026-07-29T15:46:17.07098Z","end":"2026-07-29T15:46:17.230368Z","steps":["trace[600468636] 'process raft request' (duration: 34.841751ms)","trace[600468636] 'compare' (duration: 124.310842ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:46:17.265085Z","caller":"traceutil/trace.go:171","msg":"trace[1622467994] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"178.707944ms","start":"2026-07-29T15:46:17.086342Z","end":"2026-07-29T15:46:17.26505Z","steps":["trace[1622467994] 'process raft request' (duration: 178.653743ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:46:17.265123Z","caller":"traceutil/trace.go:171","msg":"trace[214202649] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"178.738845ms","start":"2026-07-29T15:46:17.086341Z","end":"2026-07-29T15:46:17.26508Z","steps":["trace[214202649] 'process raft request' (duration: 178.512759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.415918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.040266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:46:23.416144Z","caller":"traceutil/trace.go:171","msg":"trace[848399807] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"159.799972ms","start":"2026-07-29T15:46:23.256325Z","end":"2026-07-29T15:46:23.416125Z","steps":["trace[848399807] 'read index received' (duration: 4.310444ms)","trace[848399807] 'applied index is now lower than readState.Index' (duration: 155.488128ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:46:23.416186Z","caller":"traceutil/trace.go:171","msg":"trace[955429066] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"317.852534ms","start":"2026-07-29T15:46:23.098301Z","end":"2026-07-29T15:46:23.416154Z","steps":["trace[955429066] 'process raft request' (duration: 162.49737ms)","trace[955429066] 'compare' (duration: 154.894811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:46:23.416381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.198783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-29T15:46:23.416392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.035319ms","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-07-29T15:46:23.416442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:46:23.098285Z","time spent":"318.057001ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:46:23.416447Z","caller":"traceutil/trace.go:171","msg":"trace[426380680] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2150; }","duration":"105.310606ms","start":"2026-07-29T15:46:23.311125Z","end":"2026-07-29T15:46:23.416436Z","steps":["trace[426380680] 'agreement among raft nodes before linearized reading' (duration: 105.199223ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:46:23.416509Z","caller":"traceutil/trace.go:171","msg":"trace[1440489985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2150; }","duration":"160.192584ms","start":"2026-07-29T15:46:23.256291Z","end":"2026-07-29T15:46:23.416483Z","steps":["trace[1440489985] 'agreement among raft nodes before linearized reading' (duration: 159.882285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.416492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.905086ms","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-07-29T15:46:23.416587Z","caller":"traceutil/trace.go:171","msg":"trace[755526664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2150; }","duration":"128.06053ms","start":"2026-07-29T15:46:23.288508Z","end":"2026-07-29T15:46:23.416568Z","steps":["trace[755526664] 'agreement among raft nodes before linearized reading' (duration: 127.796813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.416595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.274045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:121895"} {"level":"info","ts":"2026-07-29T15:46:23.416701Z","caller":"traceutil/trace.go:171","msg":"trace[1155140165] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2150; }","duration":"105.45561ms","start":"2026-07-29T15:46:23.311215Z","end":"2026-07-29T15:46:23.41667Z","steps":["trace[1155140165] 'agreement among raft nodes before linearized reading' (duration: 105.131201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.416837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.808974ms","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-07-29T15:46:23.4169Z","caller":"traceutil/trace.go:171","msg":"trace[635377646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2150; }","duration":"126.928318ms","start":"2026-07-29T15:46:23.289953Z","end":"2026-07-29T15:46:23.416881Z","steps":["trace[635377646] 'agreement among raft nodes before linearized reading' (duration: 126.381212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.728307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.578573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:46:23.72887Z","caller":"traceutil/trace.go:171","msg":"trace[1432051112] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"306.818467ms","start":"2026-07-29T15:46:23.422017Z","end":"2026-07-29T15:46:23.728836Z","steps":["trace[1432051112] 'read index received' (duration: 52.271432ms)","trace[1432051112] 'applied index is now lower than readState.Index' (duration: 254.541574ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:46:23.729033Z","caller":"traceutil/trace.go:171","msg":"trace[1992919081] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"307.483255ms","start":"2026-07-29T15:46:23.421523Z","end":"2026-07-29T15:46:23.729007Z","steps":["trace[1992919081] 'process raft request' (duration: 145.065758ms)","trace[1992919081] 'compare' (duration: 161.379968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:46:23.729149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:46:23.421497Z","time spent":"307.601109ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:46:23.729199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.136596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-29T15:46:23.729293Z","caller":"traceutil/trace.go:171","msg":"trace[1972802959] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2153; }","duration":"307.26845ms","start":"2026-07-29T15:46:23.422004Z","end":"2026-07-29T15:46:23.729272Z","steps":["trace[1972802959] 'agreement among raft nodes before linearized reading' (duration: 307.004983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.729342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:46:23.421997Z","time spent":"307.336162ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-29T15:46:23.729409Z","caller":"traceutil/trace.go:171","msg":"trace[470850254] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"305.886321ms","start":"2026-07-29T15:46:23.423505Z","end":"2026-07-29T15:46:23.729391Z","steps":["trace[470850254] 'process raft request' (duration: 304.978984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.729498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:46:23.423493Z","time spent":"305.971133ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:46:23.730049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.576172ms","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-07-29T15:46:23.730128Z","caller":"traceutil/trace.go:171","msg":"trace[2013089695] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2153; }","duration":"273.696575ms","start":"2026-07-29T15:46:23.456412Z","end":"2026-07-29T15:46:23.730108Z","steps":["trace[2013089695] 'agreement among raft nodes before linearized reading' (duration: 273.52802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.730292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.693702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:121895"} {"level":"info","ts":"2026-07-29T15:46:23.730432Z","caller":"traceutil/trace.go:171","msg":"trace[331824] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2153; }","duration":"307.848246ms","start":"2026-07-29T15:46:23.422533Z","end":"2026-07-29T15:46:23.730381Z","steps":["trace[331824] 'agreement among raft nodes before linearized reading' (duration: 307.389843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:46:23.730522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:46:23.422525Z","time spent":"307.983911ms","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":121918,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-29T15:49:47.098542Z","caller":"traceutil/trace.go:171","msg":"trace[1949414256] transaction","detail":"{read_only:false; response_revision:3547; number_of_response:1; }","duration":"101.473993ms","start":"2026-07-29T15:49:46.997041Z","end":"2026-07-29T15:49:47.098515Z","steps":["trace[1949414256] 'process raft request' (duration: 99.764295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:50:25.195887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.360651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:50:25.196018Z","caller":"traceutil/trace.go:171","msg":"trace[2110105883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3831; }","duration":"135.541246ms","start":"2026-07-29T15:50:25.06045Z","end":"2026-07-29T15:50:25.195991Z","steps":["trace[2110105883] 'range keys from in-memory index tree' (duration: 135.262489ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:47.752562Z","caller":"traceutil/trace.go:171","msg":"trace[1091649099] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"112.573373ms","start":"2026-07-29T15:51:47.639961Z","end":"2026-07-29T15:51:47.752534Z","steps":["trace[1091649099] 'process raft request' (duration: 38.20582ms)","trace[1091649099] 'compare' (duration: 74.098956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:47.753341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.197274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787132"} {"level":"info","ts":"2026-07-29T15:51:47.753573Z","caller":"traceutil/trace.go:171","msg":"trace[604711724] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5004; }","duration":"101.477342ms","start":"2026-07-29T15:51:47.652072Z","end":"2026-07-29T15:51:47.753549Z","steps":["trace[604711724] 'agreement among raft nodes before linearized reading' (duration: 100.501774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:47.75381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.003911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:55 size:2223260"} {"level":"info","ts":"2026-07-29T15:51:47.754219Z","caller":"traceutil/trace.go:171","msg":"trace[141672367] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:55; response_revision:5004; }","duration":"105.457274ms","start":"2026-07-29T15:51:47.648734Z","end":"2026-07-29T15:51:47.754191Z","steps":["trace[141672367] 'agreement among raft nodes before linearized reading' (duration: 103.776186ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:49.984374Z","caller":"traceutil/trace.go:171","msg":"trace[151248795] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5233; }","duration":"209.049874ms","start":"2026-07-29T15:51:49.775301Z","end":"2026-07-29T15:51:49.984351Z","steps":["trace[151248795] 'read index received' (duration: 170.762462ms)","trace[151248795] 'applied index is now lower than readState.Index' (duration: 38.286422ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:51:49.98444Z","caller":"traceutil/trace.go:171","msg":"trace[466070894] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"225.591447ms","start":"2026-07-29T15:51:49.758812Z","end":"2026-07-29T15:51:49.984403Z","steps":["trace[466070894] 'process raft request' (duration: 187.332776ms)","trace[466070894] 'compare' (duration: 38.100367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:51:49.984696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.973674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-637f344f-baf5-4c48-acb3-f70a78f6277e\" ","response":"range_response_count:1 size:3907"} {"level":"warn","ts":"2026-07-29T15:51:49.984665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.343233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-07-29T15:51:49.984737Z","caller":"traceutil/trace.go:171","msg":"trace[1687393783] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-637f344f-baf5-4c48-acb3-f70a78f6277e; range_end:; response_count:1; response_revision:5044; }","duration":"143.089527ms","start":"2026-07-29T15:51:49.841635Z","end":"2026-07-29T15:51:49.984725Z","steps":["trace[1687393783] 'agreement among raft nodes before linearized reading' (duration: 142.942923ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:49.984774Z","caller":"traceutil/trace.go:171","msg":"trace[87233644] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:5044; }","duration":"209.498137ms","start":"2026-07-29T15:51:49.775245Z","end":"2026-07-29T15:51:49.984743Z","steps":["trace[87233644] 'agreement among raft nodes before linearized reading' (duration: 209.2367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:49.985039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.844533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:797364"} {"level":"info","ts":"2026-07-29T15:51:49.985098Z","caller":"traceutil/trace.go:171","msg":"trace[1286712747] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:5044; }","duration":"161.951615ms","start":"2026-07-29T15:51:49.823131Z","end":"2026-07-29T15:51:49.985083Z","steps":["trace[1286712747] 'agreement among raft nodes before linearized reading' (duration: 161.371279ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:53.824389Z","caller":"traceutil/trace.go:171","msg":"trace[2067490249] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"131.77765ms","start":"2026-07-29T15:51:53.692589Z","end":"2026-07-29T15:51:53.824367Z","steps":["trace[2067490249] 'process raft request' (duration: 131.623886ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:53.824716Z","caller":"traceutil/trace.go:171","msg":"trace[2074025151] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"132.095339ms","start":"2026-07-29T15:51:53.692611Z","end":"2026-07-29T15:51:53.824707Z","steps":["trace[2074025151] 'process raft request' (duration: 131.721088ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:51:53.824799Z","caller":"traceutil/trace.go:171","msg":"trace[374180558] linearizableReadLoop","detail":"{readStateIndex:5304; appliedIndex:5300; }","duration":"131.578804ms","start":"2026-07-29T15:51:53.693191Z","end":"2026-07-29T15:51:53.824769Z","steps":["trace[374180558] 'read index received' (duration: 40.563406ms)","trace[374180558] 'applied index is now lower than readState.Index' (duration: 91.012928ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:51:53.824895Z","caller":"traceutil/trace.go:171","msg":"trace[1297703411] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"131.863762ms","start":"2026-07-29T15:51:53.693005Z","end":"2026-07-29T15:51:53.824868Z","steps":["trace[1297703411] 'process raft request' (duration: 131.534713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:51:53.825091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.874993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-85d9f8cf79-9gg8v\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-07-29T15:51:53.825132Z","caller":"traceutil/trace.go:171","msg":"trace[351209487] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-85d9f8cf79-9gg8v; range_end:; response_count:1; response_revision:5111; }","duration":"131.951535ms","start":"2026-07-29T15:51:53.693168Z","end":"2026-07-29T15:51:53.82512Z","steps":["trace[351209487] 'agreement among raft nodes before linearized reading' (duration: 131.738869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.205011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.344202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:52:09.205107Z","caller":"traceutil/trace.go:171","msg":"trace[1149760212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5384; }","duration":"144.486806ms","start":"2026-07-29T15:52:09.060601Z","end":"2026-07-29T15:52:09.205088Z","steps":["trace[1149760212] 'range keys from in-memory index tree' (duration: 144.249359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:09.205416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.395635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T15:52:09.205512Z","caller":"traceutil/trace.go:171","msg":"trace[817942483] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5384; }","duration":"123.547529ms","start":"2026-07-29T15:52:09.081943Z","end":"2026-07-29T15:52:09.205491Z","steps":["trace[817942483] 'count revisions from in-memory index tree' (duration: 123.264291ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642371Z","caller":"traceutil/trace.go:171","msg":"trace[2088291642] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"129.590179ms","start":"2026-07-29T15:52:48.512737Z","end":"2026-07-29T15:52:48.642327Z","steps":["trace[2088291642] 'process raft request' (duration: 98.776516ms)","trace[2088291642] 'compare' (duration: 30.532725ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:48.642459Z","caller":"traceutil/trace.go:171","msg":"trace[503884081] transaction","detail":"{read_only:false; response_revision:5764; number_of_response:1; }","duration":"128.090157ms","start":"2026-07-29T15:52:48.514283Z","end":"2026-07-29T15:52:48.642373Z","steps":["trace[503884081] 'process raft request' (duration: 127.954444ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642553Z","caller":"traceutil/trace.go:171","msg":"trace[981538861] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"128.146699ms","start":"2026-07-29T15:52:48.514376Z","end":"2026-07-29T15:52:48.642523Z","steps":["trace[981538861] 'process raft request' (duration: 127.967084ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642606Z","caller":"traceutil/trace.go:171","msg":"trace[671192809] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"128.452938ms","start":"2026-07-29T15:52:48.514145Z","end":"2026-07-29T15:52:48.642598Z","steps":["trace[671192809] 'process raft request' (duration: 128.337905ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642576Z","caller":"traceutil/trace.go:171","msg":"trace[582343475] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"128.253321ms","start":"2026-07-29T15:52:48.514294Z","end":"2026-07-29T15:52:48.642548Z","steps":["trace[582343475] 'process raft request' (duration: 127.990424ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642617Z","caller":"traceutil/trace.go:171","msg":"trace[1606235405] linearizableReadLoop","detail":"{readStateIndex:5980; appliedIndex:5971; }","duration":"128.125418ms","start":"2026-07-29T15:52:48.514473Z","end":"2026-07-29T15:52:48.642599Z","steps":["trace[1606235405] 'read index received' (duration: 23.385025ms)","trace[1606235405] 'applied index is now lower than readState.Index' (duration: 104.738903ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:48.642658Z","caller":"traceutil/trace.go:171","msg":"trace[69713693] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"128.276032ms","start":"2026-07-29T15:52:48.514376Z","end":"2026-07-29T15:52:48.642652Z","steps":["trace[69713693] 'process raft request' (duration: 127.994874ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642595Z","caller":"traceutil/trace.go:171","msg":"trace[603100867] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"128.19824ms","start":"2026-07-29T15:52:48.514346Z","end":"2026-07-29T15:52:48.642544Z","steps":["trace[603100867] 'process raft request' (duration: 127.969464ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642716Z","caller":"traceutil/trace.go:171","msg":"trace[1726071842] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"128.267663ms","start":"2026-07-29T15:52:48.514432Z","end":"2026-07-29T15:52:48.6427Z","steps":["trace[1726071842] 'process raft request' (duration: 128.021856ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642715Z","caller":"traceutil/trace.go:171","msg":"trace[1447517854] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"128.237611ms","start":"2026-07-29T15:52:48.514447Z","end":"2026-07-29T15:52:48.642684Z","steps":["trace[1447517854] 'process raft request' (duration: 128.062016ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642717Z","caller":"traceutil/trace.go:171","msg":"trace[322642415] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"127.896922ms","start":"2026-07-29T15:52:48.51473Z","end":"2026-07-29T15:52:48.642627Z","steps":["trace[322642415] 'process raft request' (duration: 127.83339ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642769Z","caller":"traceutil/trace.go:171","msg":"trace[352682319] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"128.304874ms","start":"2026-07-29T15:52:48.514393Z","end":"2026-07-29T15:52:48.642698Z","steps":["trace[352682319] 'process raft request' (duration: 128.010805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:48.642838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.355075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"warn","ts":"2026-07-29T15:52:48.642861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.713752ms","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":"info","ts":"2026-07-29T15:52:48.642912Z","caller":"traceutil/trace.go:171","msg":"trace[154253282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5774; }","duration":"124.804255ms","start":"2026-07-29T15:52:48.518098Z","end":"2026-07-29T15:52:48.642902Z","steps":["trace[154253282] 'agreement among raft nodes before linearized reading' (duration: 124.62315ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642644Z","caller":"traceutil/trace.go:171","msg":"trace[1271274037] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"128.114378ms","start":"2026-07-29T15:52:48.5145Z","end":"2026-07-29T15:52:48.642615Z","steps":["trace[1271274037] 'process raft request' (duration: 128.035866ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:48.642871Z","caller":"traceutil/trace.go:171","msg":"trace[1278231124] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5774; }","duration":"128.469058ms","start":"2026-07-29T15:52:48.514391Z","end":"2026-07-29T15:52:48.64286Z","steps":["trace[1278231124] 'agreement among raft nodes before linearized reading' (duration: 128.306094ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.259501Z","caller":"traceutil/trace.go:171","msg":"trace[1564355826] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"111.363298ms","start":"2026-07-29T15:52:49.148119Z","end":"2026-07-29T15:52:49.259483Z","steps":["trace[1564355826] 'process raft request' (duration: 36.025589ms)","trace[1564355826] 'compare' (duration: 75.123823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:52:49.25961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.955481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-29T15:52:49.259652Z","caller":"traceutil/trace.go:171","msg":"trace[1420637775] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5821; }","duration":"119.020182ms","start":"2026-07-29T15:52:49.14062Z","end":"2026-07-29T15:52:49.25964Z","steps":["trace[1420637775] 'agreement among raft nodes before linearized reading' (duration: 118.859198ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.268451Z","caller":"traceutil/trace.go:171","msg":"trace[1567458149] transaction","detail":"{read_only:false; response_revision:5822; number_of_response:1; }","duration":"100.73277ms","start":"2026-07-29T15:52:49.167682Z","end":"2026-07-29T15:52:49.268414Z","steps":["trace[1567458149] 'process raft request' (duration: 100.316468ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.268534Z","caller":"traceutil/trace.go:171","msg":"trace[982539314] transaction","detail":"{read_only:false; response_revision:5824; number_of_response:1; }","duration":"100.003019ms","start":"2026-07-29T15:52:49.16849Z","end":"2026-07-29T15:52:49.268493Z","steps":["trace[982539314] 'process raft request' (duration: 99.873245ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.268484Z","caller":"traceutil/trace.go:171","msg":"trace[2089165878] transaction","detail":"{read_only:false; response_revision:5823; number_of_response:1; }","duration":"100.575676ms","start":"2026-07-29T15:52:49.167875Z","end":"2026-07-29T15:52:49.268451Z","steps":["trace[2089165878] 'process raft request' (duration: 100.418601ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.457174Z","caller":"traceutil/trace.go:171","msg":"trace[766507285] transaction","detail":"{read_only:false; response_revision:5832; number_of_response:1; }","duration":"155.272507ms","start":"2026-07-29T15:52:49.301841Z","end":"2026-07-29T15:52:49.457114Z","steps":["trace[766507285] 'process raft request' (duration: 104.050953ms)","trace[766507285] 'compare' (duration: 50.959816ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:49.457617Z","caller":"traceutil/trace.go:171","msg":"trace[283803084] transaction","detail":"{read_only:false; response_revision:5833; number_of_response:1; }","duration":"109.908867ms","start":"2026-07-29T15:52:49.347678Z","end":"2026-07-29T15:52:49.457586Z","steps":["trace[283803084] 'process raft request' (duration: 109.386282ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:49.457889Z","caller":"traceutil/trace.go:171","msg":"trace[1151566418] linearizableReadLoop","detail":"{readStateIndex:6044; appliedIndex:6041; }","duration":"106.003477ms","start":"2026-07-29T15:52:49.351868Z","end":"2026-07-29T15:52:49.457872Z","steps":["trace[1151566418] 'read index received' (duration: 54.027862ms)","trace[1151566418] 'applied index is now lower than readState.Index' (duration: 51.827691ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:49.457902Z","caller":"traceutil/trace.go:171","msg":"trace[647812039] transaction","detail":"{read_only:false; response_revision:5834; number_of_response:1; }","duration":"107.501949ms","start":"2026-07-29T15:52:49.350385Z","end":"2026-07-29T15:52:49.457887Z","steps":["trace[647812039] 'process raft request' (duration: 107.16436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:49.458225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.281245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-07-29T15:52:49.458294Z","caller":"traceutil/trace.go:171","msg":"trace[166312056] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5834; }","duration":"106.448659ms","start":"2026-07-29T15:52:49.351827Z","end":"2026-07-29T15:52:49.458276Z","steps":["trace[166312056] 'agreement among raft nodes before linearized reading' (duration: 106.145021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.438084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.249446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T15:52:52.438198Z","caller":"traceutil/trace.go:171","msg":"trace[1553525754] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5871; }","duration":"155.41309ms","start":"2026-07-29T15:52:52.282762Z","end":"2026-07-29T15:52:52.438175Z","steps":["trace[1553525754] 'range keys from in-memory index tree' (duration: 155.05909ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:52.69282Z","caller":"traceutil/trace.go:171","msg":"trace[1524976683] linearizableReadLoop","detail":"{readStateIndex:6084; appliedIndex:6083; }","duration":"365.867031ms","start":"2026-07-29T15:52:52.326928Z","end":"2026-07-29T15:52:52.692795Z","steps":["trace[1524976683] 'read index received' (duration: 365.650895ms)","trace[1524976683] 'applied index is now lower than readState.Index' (duration: 214.966µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:52:52.692868Z","caller":"traceutil/trace.go:171","msg":"trace[972925051] transaction","detail":"{read_only:false; response_revision:5872; number_of_response:1; }","duration":"369.105162ms","start":"2026-07-29T15:52:52.323718Z","end":"2026-07-29T15:52:52.692823Z","steps":["trace[972925051] 'process raft request' (duration: 368.878476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.692979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.027956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:52:52.692985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.956314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:52:52.693023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:52.323657Z","time spent":"369.290376ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:52:52.69305Z","caller":"traceutil/trace.go:171","msg":"trace[696981503] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5872; }","duration":"366.139329ms","start":"2026-07-29T15:52:52.326896Z","end":"2026-07-29T15:52:52.693035Z","steps":["trace[696981503] 'agreement among raft nodes before linearized reading' (duration: 366.019946ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:52.693062Z","caller":"traceutil/trace.go:171","msg":"trace[1570221869] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5872; }","duration":"366.064756ms","start":"2026-07-29T15:52:52.326979Z","end":"2026-07-29T15:52:52.693044Z","steps":["trace[1570221869] 'agreement among raft nodes before linearized reading' (duration: 365.961603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.693098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:52.326883Z","time spent":"366.20584ms","remote":"127.0.0.1:53126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-07-29T15:52:52.69311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:52.326965Z","time spent":"366.132699ms","remote":"127.0.0.1:53126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-07-29T15:52:52.693117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.752946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-29T15:52:52.693168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.016001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-07-29T15:52:52.693203Z","caller":"traceutil/trace.go:171","msg":"trace[2084971915] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5872; }","duration":"271.89756ms","start":"2026-07-29T15:52:52.421287Z","end":"2026-07-29T15:52:52.693184Z","steps":["trace[2084971915] 'agreement among raft nodes before linearized reading' (duration: 271.694544ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:52:52.693213Z","caller":"traceutil/trace.go:171","msg":"trace[686188849] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5872; }","duration":"363.076412ms","start":"2026-07-29T15:52:52.330126Z","end":"2026-07-29T15:52:52.693202Z","steps":["trace[686188849] 'agreement among raft nodes before linearized reading' (duration: 362.894367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.693252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:52.330119Z","time spent":"363.125934ms","remote":"127.0.0.1:53126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6180,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-07-29T15:52:52.693243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.446353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k5pr8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-29T15:52:52.693339Z","caller":"traceutil/trace.go:171","msg":"trace[680278271] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k5pr8; range_end:; response_count:1; response_revision:5872; }","duration":"363.575817ms","start":"2026-07-29T15:52:52.329737Z","end":"2026-07-29T15:52:52.693313Z","steps":["trace[680278271] 'agreement among raft nodes before linearized reading' (duration: 363.32568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.693352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.574551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T15:52:52.693404Z","caller":"traceutil/trace.go:171","msg":"trace[1977146774] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5872; }","duration":"251.657964ms","start":"2026-07-29T15:52:52.441732Z","end":"2026-07-29T15:52:52.69339Z","steps":["trace[1977146774] 'agreement among raft nodes before linearized reading' (duration: 251.477629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:52:52.693408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:52:52.329724Z","time spent":"363.663949ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-k5pr8\" "} {"level":"info","ts":"2026-07-29T15:52:53.143057Z","caller":"traceutil/trace.go:171","msg":"trace[865942337] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"186.383286ms","start":"2026-07-29T15:52:52.956643Z","end":"2026-07-29T15:52:53.143026Z","steps":["trace[865942337] 'process raft request' (duration: 186.126749ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:53:13.904237Z","caller":"traceutil/trace.go:171","msg":"trace[855165698] transaction","detail":"{read_only:false; response_revision:6018; number_of_response:1; }","duration":"114.365831ms","start":"2026-07-29T15:53:13.789845Z","end":"2026-07-29T15:53:13.90421Z","steps":["trace[855165698] 'process raft request' (duration: 114.160265ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:53:14.023689Z","caller":"traceutil/trace.go:171","msg":"trace[1865960284] linearizableReadLoop","detail":"{readStateIndex:6235; appliedIndex:6234; }","duration":"107.084887ms","start":"2026-07-29T15:53:13.916581Z","end":"2026-07-29T15:53:14.023666Z","steps":["trace[1865960284] 'read index received' (duration: 44.892896ms)","trace[1865960284] 'applied index is now lower than readState.Index' (duration: 62.19111ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:53:14.023899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.285793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:53:14.023944Z","caller":"traceutil/trace.go:171","msg":"trace[996138069] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:6019; }","duration":"107.392586ms","start":"2026-07-29T15:53:13.916536Z","end":"2026-07-29T15:53:14.023928Z","steps":["trace[996138069] 'agreement among raft nodes before linearized reading' (duration: 107.289773ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:53:14.02387Z","caller":"traceutil/trace.go:171","msg":"trace[1942083185] transaction","detail":"{read_only:false; response_revision:6019; number_of_response:1; }","duration":"195.032859ms","start":"2026-07-29T15:53:13.828789Z","end":"2026-07-29T15:53:14.023821Z","steps":["trace[1942083185] 'process raft request' (duration: 132.692284ms)","trace[1942083185] 'compare' (duration: 62.057247ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:53:21.963059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-07-29T15:53:22.032709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"68.205639ms","hash":4039513128,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":34099200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-29T15:53:22.032777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4039513128,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-07-29T15:58:21.970009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6089} {"level":"info","ts":"2026-07-29T15:58:22.060989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6089,"took":"88.827941ms","hash":3475408040,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":23920640,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-29T15:58:22.06105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3475408040,"revision":6089,"compact-revision":2989} {"level":"info","ts":"2026-07-29T15:58:24.198573Z","caller":"traceutil/trace.go:171","msg":"trace[107728386] linearizableReadLoop","detail":"{readStateIndex:8590; appliedIndex:8589; }","duration":"137.030452ms","start":"2026-07-29T15:58:24.061513Z","end":"2026-07-29T15:58:24.198543Z","steps":["trace[107728386] 'read index received' (duration: 136.819836ms)","trace[107728386] 'applied index is now lower than readState.Index' (duration: 209.036µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:58:24.198694Z","caller":"traceutil/trace.go:171","msg":"trace[1478956384] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"194.399301ms","start":"2026-07-29T15:58:24.004244Z","end":"2026-07-29T15:58:24.198644Z","steps":["trace[1478956384] 'process raft request' (duration: 194.092032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:24.198776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.247498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:58:24.198899Z","caller":"traceutil/trace.go:171","msg":"trace[823661193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8299; }","duration":"137.435534ms","start":"2026-07-29T15:58:24.061437Z","end":"2026-07-29T15:58:24.198873Z","steps":["trace[823661193] 'agreement among raft nodes before linearized reading' (duration: 137.219668ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:34.715285Z","caller":"traceutil/trace.go:171","msg":"trace[1339366751] linearizableReadLoop","detail":"{readStateIndex:8645; appliedIndex:8644; }","duration":"110.276092ms","start":"2026-07-29T15:58:34.604985Z","end":"2026-07-29T15:58:34.715261Z","steps":["trace[1339366751] 'read index received' (duration: 110.20414ms)","trace[1339366751] 'applied index is now lower than readState.Index' (duration: 70.912µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:58:34.715391Z","caller":"traceutil/trace.go:171","msg":"trace[2024273175] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"116.523718ms","start":"2026-07-29T15:58:34.598835Z","end":"2026-07-29T15:58:34.715359Z","steps":["trace[2024273175] 'process raft request' (duration: 116.282401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:34.715508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.501798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k5pr8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-29T15:58:34.71554Z","caller":"traceutil/trace.go:171","msg":"trace[2104675328] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k5pr8; range_end:; response_count:1; response_revision:8352; }","duration":"110.580611ms","start":"2026-07-29T15:58:34.604951Z","end":"2026-07-29T15:58:34.715531Z","steps":["trace[2104675328] 'agreement among raft nodes before linearized reading' (duration: 110.397586ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:35.085237Z","caller":"traceutil/trace.go:171","msg":"trace[607209863] linearizableReadLoop","detail":"{readStateIndex:8646; appliedIndex:8645; }","duration":"201.347446ms","start":"2026-07-29T15:58:34.883866Z","end":"2026-07-29T15:58:35.085213Z","steps":["trace[607209863] 'read index received' (duration: 193.177267ms)","trace[607209863] 'applied index is now lower than readState.Index' (duration: 8.169209ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:58:35.085346Z","caller":"traceutil/trace.go:171","msg":"trace[119127680] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"300.527337ms","start":"2026-07-29T15:58:34.784796Z","end":"2026-07-29T15:58:35.085323Z","steps":["trace[119127680] 'process raft request' (duration: 292.224964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:35.085476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:34.784777Z","time spent":"300.62865ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:58:35.085477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.593653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:58:35.085554Z","caller":"traceutil/trace.go:171","msg":"trace[961087162] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8353; }","duration":"201.720657ms","start":"2026-07-29T15:58:34.883817Z","end":"2026-07-29T15:58:35.085537Z","steps":["trace[961087162] 'agreement among raft nodes before linearized reading' (duration: 201.538112ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.16524Z","caller":"traceutil/trace.go:171","msg":"trace[72678926] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"771.939646ms","start":"2026-07-29T15:58:35.393248Z","end":"2026-07-29T15:58:36.165188Z","steps":["trace[72678926] 'process raft request' (duration: 753.765296ms)","trace[72678926] 'compare' (duration: 17.347487ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:58:36.165244Z","caller":"traceutil/trace.go:171","msg":"trace[1243664958] linearizableReadLoop","detail":"{readStateIndex:8652; appliedIndex:8651; }","duration":"503.062546ms","start":"2026-07-29T15:58:35.66211Z","end":"2026-07-29T15:58:36.165172Z","steps":["trace[1243664958] 'read index received' (duration: 485.134934ms)","trace[1243664958] 'applied index is now lower than readState.Index' (duration: 17.925162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:58:36.165423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.39322Z","time spent":"772.112591ms","remote":"127.0.0.1:53078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:58:36.165738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.592821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T15:58:36.165815Z","caller":"traceutil/trace.go:171","msg":"trace[941285786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8358; }","duration":"503.724925ms","start":"2026-07-29T15:58:35.662069Z","end":"2026-07-29T15:58:36.165794Z","steps":["trace[941285786] 'agreement among raft nodes before linearized reading' (duration: 503.341794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.165917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.662054Z","time spent":"503.803988ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T15:58:36.200078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.436827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:58:36.200156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.995965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-29T15:58:36.20017Z","caller":"traceutil/trace.go:171","msg":"trace[1017790833] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8359; }","duration":"370.584561ms","start":"2026-07-29T15:58:35.829563Z","end":"2026-07-29T15:58:36.200148Z","steps":["trace[1017790833] 'agreement among raft nodes before linearized reading' (duration: 370.402256ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.200208Z","caller":"traceutil/trace.go:171","msg":"trace[937188411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8359; }","duration":"503.117687ms","start":"2026-07-29T15:58:35.697078Z","end":"2026-07-29T15:58:36.200196Z","steps":["trace[937188411] 'agreement among raft nodes before linearized reading' (duration: 502.937292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.200192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.160172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T15:58:36.200222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.829542Z","time spent":"370.668494ms","remote":"127.0.0.1:36810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:58:36.200244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.275517ms","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":"warn","ts":"2026-07-29T15:58:36.200284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.551736ms","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-07-29T15:58:36.200257Z","caller":"traceutil/trace.go:171","msg":"trace[858521345] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"365.715274ms","start":"2026-07-29T15:58:35.83452Z","end":"2026-07-29T15:58:36.200235Z","steps":["trace[858521345] 'process raft request' (duration: 365.1994ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.2003Z","caller":"traceutil/trace.go:171","msg":"trace[1433208518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8359; }","duration":"500.588607ms","start":"2026-07-29T15:58:35.699706Z","end":"2026-07-29T15:58:36.200295Z","steps":["trace[1433208518] 'agreement among raft nodes before linearized reading' (duration: 500.544206ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.200309Z","caller":"traceutil/trace.go:171","msg":"trace[825441112] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8359; }","duration":"521.354199ms","start":"2026-07-29T15:58:35.678941Z","end":"2026-07-29T15:58:36.200295Z","steps":["trace[825441112] 'agreement among raft nodes before linearized reading' (duration: 521.246136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.200317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.699696Z","time spent":"500.618078ms","remote":"127.0.0.1:53154","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":"warn","ts":"2026-07-29T15:58:36.200345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.678934Z","time spent":"521.39707ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-29T15:58:36.200386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.8345Z","time spent":"365.809908ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:58:36.200275Z","caller":"traceutil/trace.go:171","msg":"trace[183513232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8359; }","duration":"139.313106ms","start":"2026-07-29T15:58:36.060942Z","end":"2026-07-29T15:58:36.200255Z","steps":["trace[183513232] 'agreement among raft nodes before linearized reading' (duration: 139.042768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.200235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.697047Z","time spent":"503.182909ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T15:58:36.200895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.827326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T15:58:36.200956Z","caller":"traceutil/trace.go:171","msg":"trace[1392519270] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8359; }","duration":"305.893868ms","start":"2026-07-29T15:58:35.895044Z","end":"2026-07-29T15:58:36.200938Z","steps":["trace[1392519270] 'agreement among raft nodes before linearized reading' (duration: 305.041024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.200998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:58:35.894984Z","time spent":"306.003171ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-07-29T15:58:36.303637Z","caller":"traceutil/trace.go:171","msg":"trace[1467029916] linearizableReadLoop","detail":"{readStateIndex:8654; appliedIndex:8653; }","duration":"103.691897ms","start":"2026-07-29T15:58:36.199921Z","end":"2026-07-29T15:58:36.303613Z","steps":["trace[1467029916] 'read index received' (duration: 73.549912ms)","trace[1467029916] 'applied index is now lower than readState.Index' (duration: 30.140605ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:58:36.303788Z","caller":"traceutil/trace.go:171","msg":"trace[1307248366] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"130.991473ms","start":"2026-07-29T15:58:36.172741Z","end":"2026-07-29T15:58:36.303732Z","steps":["trace[1307248366] 'process raft request' (duration: 100.727815ms)","trace[1307248366] 'compare' (duration: 30.028102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:58:36.303944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.447119ms","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":"info","ts":"2026-07-29T15:58:36.304039Z","caller":"traceutil/trace.go:171","msg":"trace[949525874] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:8360; }","duration":"119.623464ms","start":"2026-07-29T15:58:36.184395Z","end":"2026-07-29T15:58:36.304019Z","steps":["trace[949525874] 'agreement among raft nodes before linearized reading' (duration: 119.373767ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.319432Z","caller":"traceutil/trace.go:171","msg":"trace[1551821431] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"114.84677ms","start":"2026-07-29T15:58:36.204562Z","end":"2026-07-29T15:58:36.319409Z","steps":["trace[1551821431] 'process raft request' (duration: 114.641884ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:36.319632Z","caller":"traceutil/trace.go:171","msg":"trace[511906927] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"114.925773ms","start":"2026-07-29T15:58:36.204659Z","end":"2026-07-29T15:58:36.319585Z","steps":["trace[511906927] 'process raft request' (duration: 114.695176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:58:36.335932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.850323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T15:58:36.336029Z","caller":"traceutil/trace.go:171","msg":"trace[970686244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8363; }","duration":"133.982266ms","start":"2026-07-29T15:58:36.202022Z","end":"2026-07-29T15:58:36.336004Z","steps":["trace[970686244] 'agreement among raft nodes before linearized reading' (duration: 133.812201ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:58:38.562292Z","caller":"traceutil/trace.go:171","msg":"trace[1739130914] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"129.359157ms","start":"2026-07-29T15:58:38.43291Z","end":"2026-07-29T15:58:38.562269Z","steps":["trace[1739130914] 'process raft request' (duration: 129.211353ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:01.670394Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002290.wal"} {"level":"info","ts":"2026-07-29T15:59:09.760422Z","caller":"traceutil/trace.go:171","msg":"trace[459229647] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"428.626429ms","start":"2026-07-29T15:59:09.331774Z","end":"2026-07-29T15:59:09.7604Z","steps":["trace[459229647] 'process raft request' (duration: 428.475025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:09.760546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:59:09.331713Z","time spent":"428.772353ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:59:09.769459Z","caller":"traceutil/trace.go:171","msg":"trace[1988797010] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"309.849728ms","start":"2026-07-29T15:59:09.45959Z","end":"2026-07-29T15:59:09.76944Z","steps":["trace[1988797010] 'process raft request' (duration: 309.718695ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:09.769445Z","caller":"traceutil/trace.go:171","msg":"trace[781151192] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"368.595866ms","start":"2026-07-29T15:59:09.400792Z","end":"2026-07-29T15:59:09.769388Z","steps":["trace[781151192] 'process raft request' (duration: 368.38909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:09.769579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:59:09.459576Z","time spent":"309.93833ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:59:09.769726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:59:09.400769Z","time spent":"368.792732ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T15:59:18.800292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.834283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T15:59:18.800484Z","caller":"traceutil/trace.go:171","msg":"trace[1924739499] linearizableReadLoop","detail":"{readStateIndex:8990; appliedIndex:8988; }","duration":"289.424119ms","start":"2026-07-29T15:59:18.511046Z","end":"2026-07-29T15:59:18.80047Z","steps":["trace[1924739499] 'read index received' (duration: 144.344129ms)","trace[1924739499] 'applied index is now lower than readState.Index' (duration: 145.07911ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:59:18.800735Z","caller":"traceutil/trace.go:171","msg":"trace[2051600251] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"277.924856ms","start":"2026-07-29T15:59:18.522627Z","end":"2026-07-29T15:59:18.800552Z","steps":["trace[2051600251] 'process raft request' (duration: 277.816603ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:18.800731Z","caller":"traceutil/trace.go:171","msg":"trace[1934898704] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"424.084546ms","start":"2026-07-29T15:59:18.376603Z","end":"2026-07-29T15:59:18.800688Z","steps":["trace[1934898704] 'process raft request' (duration: 278.78293ms)","trace[1934898704] 'compare' (duration: 144.682539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T15:59:18.800823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.139522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-29T15:59:18.800865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:59:18.376596Z","time spent":"424.201ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:59:18.800877Z","caller":"traceutil/trace.go:171","msg":"trace[1129742219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8683; }","duration":"263.238294ms","start":"2026-07-29T15:59:18.537626Z","end":"2026-07-29T15:59:18.800864Z","steps":["trace[1129742219] 'agreement among raft nodes before linearized reading' (duration: 263.055729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:18.800873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.899688ms","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-07-29T15:59:18.800868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.81665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-29T15:59:18.800943Z","caller":"traceutil/trace.go:171","msg":"trace[237318366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8683; }","duration":"261.025443ms","start":"2026-07-29T15:59:18.5399Z","end":"2026-07-29T15:59:18.800926Z","steps":["trace[237318366] 'agreement among raft nodes before linearized reading' (duration: 260.826137ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:18.800957Z","caller":"traceutil/trace.go:171","msg":"trace[851372527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8683; }","duration":"289.914613ms","start":"2026-07-29T15:59:18.511022Z","end":"2026-07-29T15:59:18.800937Z","steps":["trace[851372527] 'agreement among raft nodes before linearized reading' (duration: 289.665536ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:18.801222Z","caller":"traceutil/trace.go:171","msg":"trace[369827990] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"317.953349ms","start":"2026-07-29T15:59:18.483236Z","end":"2026-07-29T15:59:18.801189Z","steps":["trace[369827990] 'process raft request' (duration: 317.164977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:18.801301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T15:59:18.483223Z","time spent":"318.041802ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T15:59:18.924709Z","caller":"traceutil/trace.go:171","msg":"trace[1020209611] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"119.501552ms","start":"2026-07-29T15:59:18.80517Z","end":"2026-07-29T15:59:18.924671Z","steps":["trace[1020209611] 'process raft request' (duration: 95.312604ms)","trace[1020209611] 'compare' (duration: 24.016583ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:59:18.940756Z","caller":"traceutil/trace.go:171","msg":"trace[582941295] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"133.972269ms","start":"2026-07-29T15:59:18.80676Z","end":"2026-07-29T15:59:18.940732Z","steps":["trace[582941295] 'process raft request' (duration: 133.925027ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:18.941027Z","caller":"traceutil/trace.go:171","msg":"trace[972452314] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"134.316988ms","start":"2026-07-29T15:59:18.806699Z","end":"2026-07-29T15:59:18.941016Z","steps":["trace[972452314] 'process raft request' (duration: 133.847684ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:19.920136Z","caller":"traceutil/trace.go:171","msg":"trace[1096363743] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"152.090527ms","start":"2026-07-29T15:59:19.768008Z","end":"2026-07-29T15:59:19.920098Z","steps":["trace[1096363743] 'process raft request' (duration: 151.875211ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:19.93233Z","caller":"traceutil/trace.go:171","msg":"trace[2041098847] linearizableReadLoop","detail":"{readStateIndex:8999; appliedIndex:8996; }","duration":"126.495549ms","start":"2026-07-29T15:59:19.805815Z","end":"2026-07-29T15:59:19.932311Z","steps":["trace[2041098847] 'read index received' (duration: 111.322073ms)","trace[2041098847] 'applied index is now lower than readState.Index' (duration: 15.172466ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:59:19.932357Z","caller":"traceutil/trace.go:171","msg":"trace[860456973] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"129.421221ms","start":"2026-07-29T15:59:19.802921Z","end":"2026-07-29T15:59:19.932342Z","steps":["trace[860456973] 'process raft request' (duration: 129.238016ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:19.932549Z","caller":"traceutil/trace.go:171","msg":"trace[1943900388] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"128.396393ms","start":"2026-07-29T15:59:19.803995Z","end":"2026-07-29T15:59:19.932392Z","steps":["trace[1943900388] 'process raft request' (duration: 128.276429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:19.932631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.794708ms","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-07-29T15:59:19.932721Z","caller":"traceutil/trace.go:171","msg":"trace[192422375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8691; }","duration":"126.9183ms","start":"2026-07-29T15:59:19.805787Z","end":"2026-07-29T15:59:19.932705Z","steps":["trace[192422375] 'agreement among raft nodes before linearized reading' (duration: 126.615402ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:37.769294Z","caller":"traceutil/trace.go:171","msg":"trace[803723437] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"211.299687ms","start":"2026-07-29T15:59:37.557957Z","end":"2026-07-29T15:59:37.769257Z","steps":["trace[803723437] 'process raft request' (duration: 210.619778ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:37.777362Z","caller":"traceutil/trace.go:171","msg":"trace[849870852] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"214.135056ms","start":"2026-07-29T15:59:37.563196Z","end":"2026-07-29T15:59:37.777331Z","steps":["trace[849870852] 'process raft request' (duration: 213.92075ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:43.246058Z","caller":"traceutil/trace.go:171","msg":"trace[1807496762] linearizableReadLoop","detail":"{readStateIndex:9271; appliedIndex:9270; }","duration":"161.228983ms","start":"2026-07-29T15:59:43.084801Z","end":"2026-07-29T15:59:43.24603Z","steps":["trace[1807496762] 'read index received' (duration: 161.085029ms)","trace[1807496762] 'applied index is now lower than readState.Index' (duration: 142.654µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T15:59:43.246217Z","caller":"traceutil/trace.go:171","msg":"trace[1733986249] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"184.734163ms","start":"2026-07-29T15:59:43.061444Z","end":"2026-07-29T15:59:43.246179Z","steps":["trace[1733986249] 'process raft request' (duration: 184.371463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T15:59:43.246356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.393434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k5pr8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-07-29T15:59:43.246359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.538862ms","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-07-29T15:59:43.24641Z","caller":"traceutil/trace.go:171","msg":"trace[2113722878] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k5pr8; range_end:; response_count:1; response_revision:8956; }","duration":"137.505598ms","start":"2026-07-29T15:59:43.108891Z","end":"2026-07-29T15:59:43.246397Z","steps":["trace[2113722878] 'agreement among raft nodes before linearized reading' (duration: 137.314463ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T15:59:43.246425Z","caller":"traceutil/trace.go:171","msg":"trace[407349600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8956; }","duration":"161.661975ms","start":"2026-07-29T15:59:43.084747Z","end":"2026-07-29T15:59:43.246409Z","steps":["trace[407349600] 'agreement among raft nodes before linearized reading' (duration: 161.438919ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:00:29.411692Z","caller":"traceutil/trace.go:171","msg":"trace[1991807995] transaction","detail":"{read_only:false; response_revision:9402; number_of_response:1; }","duration":"122.532318ms","start":"2026-07-29T16:00:29.289055Z","end":"2026-07-29T16:00:29.411588Z","steps":["trace[1991807995] 'process raft request' (duration: 122.215219ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:00:38.691926Z","caller":"traceutil/trace.go:171","msg":"trace[500020347] transaction","detail":"{read_only:false; response_revision:9453; number_of_response:1; }","duration":"237.525453ms","start":"2026-07-29T16:00:38.454379Z","end":"2026-07-29T16:00:38.691904Z","steps":["trace[500020347] 'process raft request' (duration: 237.394219ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:00:38.804628Z","caller":"traceutil/trace.go:171","msg":"trace[723193295] transaction","detail":"{read_only:false; response_revision:9454; number_of_response:1; }","duration":"161.813969ms","start":"2026-07-29T16:00:38.64279Z","end":"2026-07-29T16:00:38.804604Z","steps":["trace[723193295] 'process raft request' (duration: 161.636794ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:01:08.401242Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T16:01:08.407272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-29T16:01:08.407415Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-29T16:03:21.9773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8288} {"level":"info","ts":"2026-07-29T16:03:22.060155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8288,"took":"79.916889ms","hash":3212476872,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":24952832,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-29T16:03:22.060259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3212476872,"revision":8288,"compact-revision":6089} {"level":"info","ts":"2026-07-29T16:08:21.983531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10512} {"level":"info","ts":"2026-07-29T16:08:22.059854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10512,"took":"73.521194ms","hash":681803471,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":26116096,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-29T16:08:22.059997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":681803471,"revision":10512,"compact-revision":8288} {"level":"warn","ts":"2026-07-29T16:12:21.764019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.262056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T16:12:21.764133Z","caller":"traceutil/trace.go:171","msg":"trace[1222637657] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:14355; }","duration":"126.432211ms","start":"2026-07-29T16:12:21.637684Z","end":"2026-07-29T16:12:21.764116Z","steps":["trace[1222637657] 'count revisions from in-memory index tree' (duration: 126.158284ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:12:21.76451Z","caller":"traceutil/trace.go:171","msg":"trace[14031434] linearizableReadLoop","detail":"{readStateIndex:14847; appliedIndex:14846; }","duration":"111.591206ms","start":"2026-07-29T16:12:21.65291Z","end":"2026-07-29T16:12:21.764502Z","steps":["trace[14031434] 'read index received' (duration: 110.764123ms)","trace[14031434] 'applied index is now lower than readState.Index' (duration: 826.483µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T16:12:21.764581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.674318ms","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-07-29T16:12:21.764607Z","caller":"traceutil/trace.go:171","msg":"trace[1171361716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14356; }","duration":"111.72669ms","start":"2026-07-29T16:12:21.652873Z","end":"2026-07-29T16:12:21.764599Z","steps":["trace[1171361716] 'agreement among raft nodes before linearized reading' (duration: 111.664548ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:12:21.764802Z","caller":"traceutil/trace.go:171","msg":"trace[497962590] transaction","detail":"{read_only:false; response_revision:14356; number_of_response:1; }","duration":"119.133107ms","start":"2026-07-29T16:12:21.645631Z","end":"2026-07-29T16:12:21.764764Z","steps":["trace[497962590] 'process raft request' (duration: 118.215231ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:12:40.936772Z","caller":"traceutil/trace.go:171","msg":"trace[1751197229] transaction","detail":"{read_only:false; response_revision:14452; number_of_response:1; }","duration":"116.950026ms","start":"2026-07-29T16:12:40.819794Z","end":"2026-07-29T16:12:40.936744Z","steps":["trace[1751197229] 'process raft request' (duration: 116.75291ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:12:41.057117Z","caller":"traceutil/trace.go:171","msg":"trace[1400141580] transaction","detail":"{read_only:false; response_revision:14453; number_of_response:1; }","duration":"234.992332ms","start":"2026-07-29T16:12:40.822102Z","end":"2026-07-29T16:12:41.057094Z","steps":["trace[1400141580] 'process raft request' (duration: 234.807787ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:12:58.47141Z","caller":"traceutil/trace.go:171","msg":"trace[1169698838] transaction","detail":"{read_only:false; response_revision:14558; number_of_response:1; }","duration":"300.685196ms","start":"2026-07-29T16:12:58.170702Z","end":"2026-07-29T16:12:58.471387Z","steps":["trace[1169698838] 'process raft request' (duration: 300.507771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:12:58.471572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T16:12:58.170686Z","time spent":"300.80862ms","remote":"127.0.0.1:53154","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-07-29T16:13:21.99156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12736} {"level":"info","ts":"2026-07-29T16:13:22.074343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12736,"took":"80.057155ms","hash":4232700806,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":29253632,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-29T16:13:22.074404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4232700806,"revision":12736,"compact-revision":10512} {"level":"info","ts":"2026-07-29T16:14:53.120556Z","caller":"traceutil/trace.go:171","msg":"trace[1350437656] linearizableReadLoop","detail":"{readStateIndex:16379; appliedIndex:16378; }","duration":"101.875789ms","start":"2026-07-29T16:14:53.018655Z","end":"2026-07-29T16:14:53.120531Z","steps":["trace[1350437656] 'read index received' (duration: 101.728655ms)","trace[1350437656] 'applied index is now lower than readState.Index' (duration: 146.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T16:14:53.120594Z","caller":"traceutil/trace.go:171","msg":"trace[1196363224] transaction","detail":"{read_only:false; response_revision:15842; number_of_response:1; }","duration":"104.387079ms","start":"2026-07-29T16:14:53.01618Z","end":"2026-07-29T16:14:53.120568Z","steps":["trace[1196363224] 'process raft request' (duration: 104.137192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:14:53.120797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.124056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-29T16:14:53.120861Z","caller":"traceutil/trace.go:171","msg":"trace[477758584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15842; }","duration":"102.236619ms","start":"2026-07-29T16:14:53.018607Z","end":"2026-07-29T16:14:53.120843Z","steps":["trace[477758584] 'agreement among raft nodes before linearized reading' (duration: 102.036533ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:18:21.998361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15005} {"level":"info","ts":"2026-07-29T16:18:22.106251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15005,"took":"105.266793ms","hash":4248653678,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-29T16:18:22.106325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248653678,"revision":15005,"compact-revision":12736} {"level":"info","ts":"2026-07-29T16:20:25.751048Z","caller":"traceutil/trace.go:171","msg":"trace[765623374] linearizableReadLoop","detail":"{readStateIndex:18920; appliedIndex:18919; }","duration":"203.644958ms","start":"2026-07-29T16:20:25.547375Z","end":"2026-07-29T16:20:25.75102Z","steps":["trace[765623374] 'read index received' (duration: 203.38019ms)","trace[765623374] 'applied index is now lower than readState.Index' (duration: 263.598µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T16:20:25.7512Z","caller":"traceutil/trace.go:171","msg":"trace[214581173] transaction","detail":"{read_only:false; response_revision:18299; number_of_response:1; }","duration":"207.387692ms","start":"2026-07-29T16:20:25.543796Z","end":"2026-07-29T16:20:25.751184Z","steps":["trace[214581173] 'process raft request' (duration: 207.036573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:20:25.751476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.087641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-29T16:20:25.751542Z","caller":"traceutil/trace.go:171","msg":"trace[1634934240] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18299; }","duration":"204.202954ms","start":"2026-07-29T16:20:25.547322Z","end":"2026-07-29T16:20:25.751525Z","steps":["trace[1634934240] 'agreement among raft nodes before linearized reading' (duration: 204.022719ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:20:25.763006Z","caller":"traceutil/trace.go:171","msg":"trace[413286486] transaction","detail":"{read_only:false; response_revision:18300; number_of_response:1; }","duration":"180.188125ms","start":"2026-07-29T16:20:25.582798Z","end":"2026-07-29T16:20:25.762986Z","steps":["trace[413286486] 'process raft request' (duration: 179.946118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:20:25.763064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.584322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-29T16:20:25.763109Z","caller":"traceutil/trace.go:171","msg":"trace[5308763] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18301; }","duration":"177.676635ms","start":"2026-07-29T16:20:25.585419Z","end":"2026-07-29T16:20:25.763095Z","steps":["trace[5308763] 'agreement among raft nodes before linearized reading' (duration: 177.457358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:20:25.763234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.84455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T16:20:25.763297Z","caller":"traceutil/trace.go:171","msg":"trace[937313644] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:18301; }","duration":"131.951473ms","start":"2026-07-29T16:20:25.631329Z","end":"2026-07-29T16:20:25.76328Z","steps":["trace[937313644] 'agreement among raft nodes before linearized reading' (duration: 131.835239ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:20:30.989449Z","caller":"traceutil/trace.go:171","msg":"trace[395298697] linearizableReadLoop","detail":"{readStateIndex:18953; appliedIndex:18952; }","duration":"223.064149ms","start":"2026-07-29T16:20:30.766348Z","end":"2026-07-29T16:20:30.989412Z","steps":["trace[395298697] 'read index received' (duration: 193.977399ms)","trace[395298697] 'applied index is now lower than readState.Index' (duration: 29.08534ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T16:20:30.989499Z","caller":"traceutil/trace.go:171","msg":"trace[1264031857] transaction","detail":"{read_only:false; response_revision:18331; number_of_response:1; }","duration":"289.991031ms","start":"2026-07-29T16:20:30.699479Z","end":"2026-07-29T16:20:30.98947Z","steps":["trace[1264031857] 'process raft request' (duration: 260.782888ms)","trace[1264031857] 'compare' (duration: 28.745761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T16:20:30.989716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.353446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T16:20:30.989775Z","caller":"traceutil/trace.go:171","msg":"trace[1980386617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18331; }","duration":"223.424158ms","start":"2026-07-29T16:20:30.766333Z","end":"2026-07-29T16:20:30.989758Z","steps":["trace[1980386617] 'agreement among raft nodes before linearized reading' (duration: 223.230163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:20:32.191426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.417175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7zq6m\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-07-29T16:20:32.19152Z","caller":"traceutil/trace.go:171","msg":"trace[1630547214] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7zq6m; range_end:; response_count:1; response_revision:18347; }","duration":"169.560639ms","start":"2026-07-29T16:20:32.021938Z","end":"2026-07-29T16:20:32.191498Z","steps":["trace[1630547214] 'range keys from in-memory index tree' (duration: 169.227971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:20:32.191484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.982665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T16:20:32.191685Z","caller":"traceutil/trace.go:171","msg":"trace[142926577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18347; }","duration":"131.185651ms","start":"2026-07-29T16:20:32.060446Z","end":"2026-07-29T16:20:32.191631Z","steps":["trace[142926577] 'range keys from in-memory index tree' (duration: 130.914854ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:22:10.688617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T16:22:10.694935Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-29T16:22:10.695082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-29T16:23:22.004641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17451} {"level":"info","ts":"2026-07-29T16:23:22.108844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17451,"took":"100.708337ms","hash":2107072446,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":34226176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-29T16:23:22.108989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2107072446,"revision":17451,"compact-revision":15005} {"level":"info","ts":"2026-07-29T16:26:39.366065Z","caller":"traceutil/trace.go:171","msg":"trace[2019750420] transaction","detail":"{read_only:false; response_revision:21151; number_of_response:1; }","duration":"106.545779ms","start":"2026-07-29T16:26:39.259483Z","end":"2026-07-29T16:26:39.366029Z","steps":["trace[2019750420] 'process raft request' (duration: 106.361434ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:26:39.374364Z","caller":"traceutil/trace.go:171","msg":"trace[195605062] transaction","detail":"{read_only:false; response_revision:21152; number_of_response:1; }","duration":"103.021932ms","start":"2026-07-29T16:26:39.271297Z","end":"2026-07-29T16:26:39.374319Z","steps":["trace[195605062] 'process raft request' (duration: 102.792755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:26:39.677586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.941927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T16:26:39.677752Z","caller":"traceutil/trace.go:171","msg":"trace[2140771647] transaction","detail":"{read_only:false; response_revision:21153; number_of_response:1; }","duration":"298.898052ms","start":"2026-07-29T16:26:39.378828Z","end":"2026-07-29T16:26:39.677726Z","steps":["trace[2140771647] 'process raft request' (duration: 149.682967ms)","trace[2140771647] 'compare' (duration: 148.490805ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T16:26:39.69278Z","caller":"traceutil/trace.go:171","msg":"trace[1010511301] transaction","detail":"{read_only:false; response_revision:21154; number_of_response:1; }","duration":"313.738844ms","start":"2026-07-29T16:26:39.379014Z","end":"2026-07-29T16:26:39.692753Z","steps":["trace[1010511301] 'process raft request' (duration: 313.453246ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:26:39.692785Z","caller":"traceutil/trace.go:171","msg":"trace[2137898862] linearizableReadLoop","detail":"{readStateIndex:21870; appliedIndex:21867; }","duration":"111.004413ms","start":"2026-07-29T16:26:39.581742Z","end":"2026-07-29T16:26:39.692746Z","steps":["trace[2137898862] 'read index received' (duration: 110.717915ms)","trace[2137898862] 'applied index is now lower than readState.Index' (duration: 285.478µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T16:26:39.692826Z","caller":"traceutil/trace.go:171","msg":"trace[1354133281] transaction","detail":"{read_only:false; response_revision:21155; number_of_response:1; }","duration":"313.631821ms","start":"2026-07-29T16:26:39.379162Z","end":"2026-07-29T16:26:39.692794Z","steps":["trace[1354133281] 'process raft request' (duration: 313.481337ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:26:39.692874Z","caller":"traceutil/trace.go:171","msg":"trace[682534592] transaction","detail":"{read_only:false; response_revision:21156; number_of_response:1; }","duration":"190.370367ms","start":"2026-07-29T16:26:39.502463Z","end":"2026-07-29T16:26:39.692833Z","steps":["trace[682534592] 'process raft request' (duration: 190.230873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:26:39.693016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T16:26:39.378972Z","time spent":"313.874028ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T16:26:39.69304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T16:26:39.379134Z","time spent":"313.762645ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T16:26:39.693046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.292261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T16:26:39.693091Z","caller":"traceutil/trace.go:171","msg":"trace[543820599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21156; }","duration":"111.395544ms","start":"2026-07-29T16:26:39.581679Z","end":"2026-07-29T16:26:39.693074Z","steps":["trace[543820599] 'agreement among raft nodes before linearized reading' (duration: 111.180378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T16:26:39.972373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.307843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T16:26:39.972587Z","caller":"traceutil/trace.go:171","msg":"trace[695042015] transaction","detail":"{read_only:false; response_revision:21158; number_of_response:1; }","duration":"272.647943ms","start":"2026-07-29T16:26:39.699926Z","end":"2026-07-29T16:26:39.972574Z","steps":["trace[695042015] 'process raft request' (duration: 272.54486ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:26:39.972683Z","caller":"traceutil/trace.go:171","msg":"trace[960154089] transaction","detail":"{read_only:false; response_revision:21157; number_of_response:1; }","duration":"364.474173ms","start":"2026-07-29T16:26:39.608202Z","end":"2026-07-29T16:26:39.972676Z","steps":["trace[960154089] 'process raft request' (duration: 212.79835ms)","trace[960154089] 'compare' (duration: 151.008334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T16:26:39.972723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T16:26:39.608168Z","time spent":"364.533555ms","remote":"127.0.0.1:53154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T16:26:40.469253Z","caller":"traceutil/trace.go:171","msg":"trace[1101272775] transaction","detail":"{read_only:false; response_revision:21160; number_of_response:1; }","duration":"173.065207ms","start":"2026-07-29T16:26:40.29616Z","end":"2026-07-29T16:26:40.469225Z","steps":["trace[1101272775] 'process raft request' (duration: 172.903583ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T16:28:22.010536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19817} {"level":"info","ts":"2026-07-29T16:28:22.12166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19817,"took":"107.776793ms","hash":65926833,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":29827072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-29T16:28:22.121713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":65926833,"revision":19817,"compact-revision":17451} {"level":"info","ts":"2026-07-29T16:33:22.015132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21999} {"level":"info","ts":"2026-07-29T16:33:22.124347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21999,"took":"105.303006ms","hash":354435086,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":44191744,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-29T16:33:22.124404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":354435086,"revision":21999,"compact-revision":19817} {"level":"info","ts":"2026-07-29T16:37:47.743887Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006d69.wal"} {"level":"info","ts":"2026-07-29T16:38:22.025202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25147} {"level":"info","ts":"2026-07-29T16:38:22.161861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25147,"took":"132.425895ms","hash":4005877468,"current-db-size-bytes":58011648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-29T16:38:22.161927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4005877468,"revision":25147,"compact-revision":21999}