{"level":"warn","ts":"2026-08-31T21:20:39.605985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T21:20:39.606097Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.128:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.128:2380","--initial-cluster=instance=https://162.253.55.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.128: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-08-31T21:20:39.606177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T21:20:39.606195Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-08-31T21:20:39.606222Z","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-08-31T21:20:39.607131Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"]} {"level":"info","ts":"2026-08-31T21:20:39.60724Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.128: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-08-31T21:20:39.612595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.047119ms"} {"level":"info","ts":"2026-08-31T21:20:39.619351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3e8b7bea2e3a32b","cluster-id":"ca619600f96ab468"} {"level":"info","ts":"2026-08-31T21:20:39.619428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T21:20:39.619465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 0"} {"level":"info","ts":"2026-08-31T21:20:39.619477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3e8b7bea2e3a32b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T21:20:39.619496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 1"} {"level":"info","ts":"2026-08-31T21:20:39.619541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"warn","ts":"2026-08-31T21:20:39.625403Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T21:20:39.627042Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T21:20:39.628543Z","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-08-31T21:20:39.631043Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3e8b7bea2e3a32b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T21:20:39.634356Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3e8b7bea2e3a32b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T21:20:39.634497Z","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-08-31T21:20:39.634696Z","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-08-31T21:20:39.634726Z","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-08-31T21:20:39.63862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"info","ts":"2026-08-31T21:20:39.638801Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","added-peer-id":"e3e8b7bea2e3a32b","added-peer-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-08-31T21:20:39.638933Z","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-08-31T21:20:39.639039Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-08-31T21:20:39.639057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-08-31T21:20:39.63949Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3e8b7bea2e3a32b","initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T21:20:39.639567Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T21:20:40.020415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T21:20:40.020512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T21:20:40.020544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgPreVoteResp from e3e8b7bea2e3a32b at term 1"} {"level":"info","ts":"2026-08-31T21:20:40.020611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became candidate at term 2"} {"level":"info","ts":"2026-08-31T21:20:40.020632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgVoteResp from e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-08-31T21:20:40.020651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became leader at term 2"} {"level":"info","ts":"2026-08-31T21:20:40.020667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3e8b7bea2e3a32b elected leader e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-08-31T21:20:40.022078Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:20:40.023265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T21:20:40.023276Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3e8b7bea2e3a32b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.128:2379]}","request-path":"/0/members/e3e8b7bea2e3a32b/attributes","cluster-id":"ca619600f96ab468","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T21:20:40.023333Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T21:20:40.023689Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T21:20:40.023723Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T21:20:40.023867Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:20:40.024057Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:20:40.024123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:20:40.026699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T21:20:40.042406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.128:2379"} {"level":"info","ts":"2026-08-31T21:21:00.10539Z","caller":"traceutil/trace.go:171","msg":"trace[1913996117] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"108.820296ms","start":"2026-08-31T21:20:59.996522Z","end":"2026-08-31T21:21:00.105342Z","steps":["trace[1913996117] 'read index received' (duration: 108.523238ms)","trace[1913996117] 'applied index is now lower than readState.Index' (duration: 295.398µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:21:00.105434Z","caller":"traceutil/trace.go:171","msg":"trace[131556376] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"110.677242ms","start":"2026-08-31T21:20:59.994727Z","end":"2026-08-31T21:21:00.105405Z","steps":["trace[131556376] 'process raft request' (duration: 110.418855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:21:00.105669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.106615ms","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-08-31T21:21:00.105913Z","caller":"traceutil/trace.go:171","msg":"trace[2131299029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"109.442745ms","start":"2026-08-31T21:20:59.996416Z","end":"2026-08-31T21:21:00.105859Z","steps":["trace[2131299029] 'agreement among raft nodes before linearized reading' (duration: 109.093204ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:17.823543Z","caller":"traceutil/trace.go:171","msg":"trace[291643984] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"228.381848ms","start":"2026-08-31T21:21:17.595112Z","end":"2026-08-31T21:21:17.823494Z","steps":["trace[291643984] 'process raft request' (duration: 221.341759ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:17.823858Z","caller":"traceutil/trace.go:171","msg":"trace[399404489] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"219.932667ms","start":"2026-08-31T21:21:17.603889Z","end":"2026-08-31T21:21:17.823822Z","steps":["trace[399404489] 'process raft request' (duration: 219.565277ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:17.824083Z","caller":"traceutil/trace.go:171","msg":"trace[1648718908] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"219.3763ms","start":"2026-08-31T21:21:17.604678Z","end":"2026-08-31T21:21:17.824054Z","steps":["trace[1648718908] 'process raft request' (duration: 219.123563ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:23.916952Z","caller":"traceutil/trace.go:171","msg":"trace[876335413] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"124.909717ms","start":"2026-08-31T21:21:23.792019Z","end":"2026-08-31T21:21:23.916929Z","steps":["trace[876335413] 'process raft request' (duration: 124.568387ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:24.420896Z","caller":"traceutil/trace.go:171","msg":"trace[961468553] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"103.990706ms","start":"2026-08-31T21:21:24.316879Z","end":"2026-08-31T21:21:24.42087Z","steps":["trace[961468553] 'process raft request' (duration: 103.927644ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:24.420926Z","caller":"traceutil/trace.go:171","msg":"trace[1299033802] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"105.861152ms","start":"2026-08-31T21:21:24.31503Z","end":"2026-08-31T21:21:24.420891Z","steps":["trace[1299033802] 'process raft request' (duration: 88.792055ms)","trace[1299033802] 'compare' (duration: 16.84616ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:21:24.948076Z","caller":"traceutil/trace.go:171","msg":"trace[1327599425] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"444.379469ms","start":"2026-08-31T21:21:24.50367Z","end":"2026-08-31T21:21:24.948049Z","steps":["trace[1327599425] 'read index received' (duration: 433.282649ms)","trace[1327599425] 'applied index is now lower than readState.Index' (duration: 11.09599ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:21:24.948167Z","caller":"traceutil/trace.go:171","msg":"trace[1416251454] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"533.510644ms","start":"2026-08-31T21:21:24.414549Z","end":"2026-08-31T21:21:24.94806Z","steps":["trace[1416251454] 'process raft request' (duration: 522.511407ms)","trace[1416251454] 'compare' (duration: 10.391279ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:21:24.948433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.706578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h7jpd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-31T21:21:24.948533Z","caller":"traceutil/trace.go:171","msg":"trace[1294900879] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h7jpd; range_end:; response_count:1; response_revision:627; }","duration":"444.876053ms","start":"2026-08-31T21:21:24.503639Z","end":"2026-08-31T21:21:24.948515Z","steps":["trace[1294900879] 'agreement among raft nodes before linearized reading' (duration: 444.496052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:21:24.948579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:21:24.503626Z","time spent":"444.941225ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-h7jpd\" "} {"level":"warn","ts":"2026-08-31T21:21:24.948746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:21:24.414514Z","time spent":"533.72025ms","remote":"127.0.0.1:44062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:22:08.831788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.258951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T21:22:08.831875Z","caller":"traceutil/trace.go:171","msg":"trace[1133063095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"208.398765ms","start":"2026-08-31T21:22:08.623459Z","end":"2026-08-31T21:22:08.831858Z","steps":["trace[1133063095] 'range keys from in-memory index tree' (duration: 208.083606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:08.83196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.282754ms","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-08-31T21:22:08.832127Z","caller":"traceutil/trace.go:171","msg":"trace[133787138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"202.464698ms","start":"2026-08-31T21:22:08.629598Z","end":"2026-08-31T21:22:08.832063Z","steps":["trace[133787138] 'range keys from in-memory index tree' (duration: 202.084807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:33.081317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.35777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T21:22:33.081409Z","caller":"traceutil/trace.go:171","msg":"trace[1561957995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1462; }","duration":"119.502494ms","start":"2026-08-31T21:22:32.961886Z","end":"2026-08-31T21:22:33.081388Z","steps":["trace[1561957995] 'range keys from in-memory index tree' (duration: 119.124213ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:58.492815Z","caller":"traceutil/trace.go:171","msg":"trace[1777078115] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"218.647058ms","start":"2026-08-31T21:22:58.27414Z","end":"2026-08-31T21:22:58.492787Z","steps":["trace[1777078115] 'process raft request' (duration: 218.487023ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:58.556293Z","caller":"traceutil/trace.go:171","msg":"trace[1888733409] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"133.564399ms","start":"2026-08-31T21:22:58.422699Z","end":"2026-08-31T21:22:58.556264Z","steps":["trace[1888733409] 'process raft request' (duration: 115.301801ms)","trace[1888733409] 'compare' (duration: 18.138845ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:58.895202Z","caller":"traceutil/trace.go:171","msg":"trace[155751487] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"189.704894ms","start":"2026-08-31T21:22:58.705457Z","end":"2026-08-31T21:22:58.895162Z","steps":["trace[155751487] 'process raft request' (duration: 176.431933ms)","trace[155751487] 'compare' (duration: 12.804578ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:58.895233Z","caller":"traceutil/trace.go:171","msg":"trace[86130078] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"140.905516ms","start":"2026-08-31T21:22:58.754301Z","end":"2026-08-31T21:22:58.895207Z","steps":["trace[86130078] 'read index received' (duration: 127.627084ms)","trace[86130078] 'applied index is now lower than readState.Index' (duration: 13.277222ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:22:58.898151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.834962ms","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-08-31T21:22:58.898434Z","caller":"traceutil/trace.go:171","msg":"trace[1878147630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"144.12614ms","start":"2026-08-31T21:22:58.754285Z","end":"2026-08-31T21:22:58.898411Z","steps":["trace[1878147630] 'agreement among raft nodes before linearized reading' (duration: 141.04924ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:23:53.623083Z","caller":"traceutil/trace.go:171","msg":"trace[538903110] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2308; }","duration":"252.871586ms","start":"2026-08-31T21:23:53.37017Z","end":"2026-08-31T21:23:53.623041Z","steps":["trace[538903110] 'read index received' (duration: 252.620268ms)","trace[538903110] 'applied index is now lower than readState.Index' (duration: 249.777µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:23:53.623255Z","caller":"traceutil/trace.go:171","msg":"trace[1025338175] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"298.83838ms","start":"2026-08-31T21:23:53.324354Z","end":"2026-08-31T21:23:53.623192Z","steps":["trace[1025338175] 'process raft request' (duration: 298.441778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:53.623546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.351129ms","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-08-31T21:23:53.623704Z","caller":"traceutil/trace.go:171","msg":"trace[1711944207] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2229; }","duration":"253.541725ms","start":"2026-08-31T21:23:53.370133Z","end":"2026-08-31T21:23:53.623675Z","steps":["trace[1711944207] 'agreement among raft nodes before linearized reading' (duration: 253.125473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:53.927881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.10837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:23:53.928032Z","caller":"traceutil/trace.go:171","msg":"trace[1611662506] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2309; }","duration":"304.793416ms","start":"2026-08-31T21:23:53.623218Z","end":"2026-08-31T21:23:53.928012Z","steps":["trace[1611662506] 'read index received' (duration: 14.15875ms)","trace[1611662506] 'applied index is now lower than readState.Index' (duration: 290.632886ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:23:53.928128Z","caller":"traceutil/trace.go:171","msg":"trace[2597194] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"339.880016ms","start":"2026-08-31T21:23:53.588157Z","end":"2026-08-31T21:23:53.928037Z","steps":["trace[2597194] 'process raft request' (duration: 49.291702ms)","trace[2597194] 'compare' (duration: 289.918435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:23:53.928282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.869341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-31T21:23:53.928317Z","caller":"traceutil/trace.go:171","msg":"trace[954550381] range","detail":"{range_begin:/registry/masterleases/162.253.55.128; range_end:; response_count:1; response_revision:2230; }","duration":"449.951044ms","start":"2026-08-31T21:23:53.478356Z","end":"2026-08-31T21:23:53.928307Z","steps":["trace[954550381] 'agreement among raft nodes before linearized reading' (duration: 449.768149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:53.928356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:23:53.478325Z","time spent":"450.022077ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.128\" "} {"level":"warn","ts":"2026-08-31T21:23:53.928498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.611211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-31T21:23:53.928746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.964844ms","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-08-31T21:23:53.928822Z","caller":"traceutil/trace.go:171","msg":"trace[412871683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2230; }","duration":"271.795627ms","start":"2026-08-31T21:23:53.656793Z","end":"2026-08-31T21:23:53.928588Z","steps":["trace[412871683] 'agreement among raft nodes before linearized reading' (duration: 271.512758ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:23:53.928945Z","caller":"traceutil/trace.go:171","msg":"trace[372944046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2230; }","duration":"294.217512ms","start":"2026-08-31T21:23:53.634655Z","end":"2026-08-31T21:23:53.928872Z","steps":["trace[372944046] 'agreement among raft nodes before linearized reading' (duration: 293.610894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:53.928805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:23:53.588125Z","time spent":"340.187366ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:25:03.442737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.128637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:25:03.442869Z","caller":"traceutil/trace.go:171","msg":"trace[1578736742] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2905; }","duration":"265.226937ms","start":"2026-08-31T21:25:03.177625Z","end":"2026-08-31T21:25:03.442852Z","steps":["trace[1578736742] 'read index received' (duration: 10.879361ms)","trace[1578736742] 'applied index is now lower than readState.Index' (duration: 254.346356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:25:03.442961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.33603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:25:03.442992Z","caller":"traceutil/trace.go:171","msg":"trace[2011897496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2805; }","duration":"265.413272ms","start":"2026-08-31T21:25:03.17757Z","end":"2026-08-31T21:25:03.442984Z","steps":["trace[2011897496] 'agreement among raft nodes before linearized reading' (duration: 265.328899ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:25:03.443235Z","caller":"traceutil/trace.go:171","msg":"trace[223486918] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"346.452061ms","start":"2026-08-31T21:25:03.09674Z","end":"2026-08-31T21:25:03.443192Z","steps":["trace[223486918] 'process raft request' (duration: 142.798469ms)","trace[223486918] 'compare' (duration: 202.689413ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:25:03.4435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:25:03.096714Z","time spent":"346.609725ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:28:21.107085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.74918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:28:21.107357Z","caller":"traceutil/trace.go:171","msg":"trace[2139473470] transaction","detail":"{read_only:false; response_revision:4043; number_of_response:1; }","duration":"181.587812ms","start":"2026-08-31T21:28:20.925741Z","end":"2026-08-31T21:28:21.107329Z","steps":["trace[2139473470] 'process raft request' (duration: 70.314917ms)","trace[2139473470] 'compare' (duration: 110.42019ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:28:26.259519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.925556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:28:26.259645Z","caller":"traceutil/trace.go:171","msg":"trace[1410761642] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4072; }","duration":"137.108651ms","start":"2026-08-31T21:28:26.122504Z","end":"2026-08-31T21:28:26.259613Z","steps":["trace[1410761642] 'count revisions from in-memory index tree' (duration: 136.786691ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:59.662379Z","caller":"traceutil/trace.go:171","msg":"trace[1022919231] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"137.456773ms","start":"2026-08-31T21:29:59.524891Z","end":"2026-08-31T21:29:59.662348Z","steps":["trace[1022919231] 'process raft request' (duration: 137.278528ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:03.8448Z","caller":"traceutil/trace.go:171","msg":"trace[564473252] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"162.884375ms","start":"2026-08-31T21:30:03.681885Z","end":"2026-08-31T21:30:03.84477Z","steps":["trace[564473252] 'process raft request' (duration: 162.682499ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:03.984224Z","caller":"traceutil/trace.go:171","msg":"trace[79026337] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5486; }","duration":"204.242923ms","start":"2026-08-31T21:30:03.779935Z","end":"2026-08-31T21:30:03.984178Z","steps":["trace[79026337] 'read index received' (duration: 64.629037ms)","trace[79026337] 'applied index is now lower than readState.Index' (duration: 139.612116ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:30:03.984456Z","caller":"traceutil/trace.go:171","msg":"trace[1607104945] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"268.319234ms","start":"2026-08-31T21:30:03.716091Z","end":"2026-08-31T21:30:03.98441Z","steps":["trace[1607104945] 'process raft request' (duration: 220.504688ms)","trace[1607104945] 'compare' (duration: 47.369773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:30:03.984595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.627234ms","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-08-31T21:30:03.984639Z","caller":"traceutil/trace.go:171","msg":"trace[1860616285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5292; }","duration":"204.710596ms","start":"2026-08-31T21:30:03.779916Z","end":"2026-08-31T21:30:03.984626Z","steps":["trace[1860616285] 'agreement among raft nodes before linearized reading' (duration: 204.475249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:30:03.991638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.605265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:30:03.99172Z","caller":"traceutil/trace.go:171","msg":"trace[68298706] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5293; }","duration":"140.741179ms","start":"2026-08-31T21:30:03.850961Z","end":"2026-08-31T21:30:03.991702Z","steps":["trace[68298706] 'agreement among raft nodes before linearized reading' (duration: 140.613145ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:05.624322Z","caller":"traceutil/trace.go:171","msg":"trace[42791537] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5515; }","duration":"168.17705ms","start":"2026-08-31T21:30:05.456117Z","end":"2026-08-31T21:30:05.624294Z","steps":["trace[42791537] 'read index received' (duration: 168.090807ms)","trace[42791537] 'applied index is now lower than readState.Index' (duration: 84.513µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:30:05.624551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.401326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:30:05.624647Z","caller":"traceutil/trace.go:171","msg":"trace[1650471674] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5319; }","duration":"168.53876ms","start":"2026-08-31T21:30:05.456087Z","end":"2026-08-31T21:30:05.624626Z","steps":["trace[1650471674] 'agreement among raft nodes before linearized reading' (duration: 168.322924ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:05.625059Z","caller":"traceutil/trace.go:171","msg":"trace[893599247] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"175.313208ms","start":"2026-08-31T21:30:05.449677Z","end":"2026-08-31T21:30:05.62499Z","steps":["trace[893599247] 'process raft request' (duration: 174.437862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:30:05.635126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.484314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T21:30:05.63519Z","caller":"traceutil/trace.go:171","msg":"trace[1256493936] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5320; }","duration":"113.618258ms","start":"2026-08-31T21:30:05.521557Z","end":"2026-08-31T21:30:05.635175Z","steps":["trace[1256493936] 'agreement among raft nodes before linearized reading' (duration: 113.383121ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:05.635468Z","caller":"traceutil/trace.go:171","msg":"trace[743175501] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"139.816632ms","start":"2026-08-31T21:30:05.495148Z","end":"2026-08-31T21:30:05.634965Z","steps":["trace[743175501] 'process raft request' (duration: 139.653348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:30:20.800857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.193908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:30:20.801036Z","caller":"traceutil/trace.go:171","msg":"trace[790967335] linearizableReadLoop","detail":"{readStateIndex:5777; appliedIndex:5776; }","duration":"244.844158ms","start":"2026-08-31T21:30:20.556165Z","end":"2026-08-31T21:30:20.801009Z","steps":["trace[790967335] 'read index received' (duration: 74.371161ms)","trace[790967335] 'applied index is now lower than readState.Index' (duration: 170.470757ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:30:20.801187Z","caller":"traceutil/trace.go:171","msg":"trace[1823530764] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"278.092478ms","start":"2026-08-31T21:30:20.52306Z","end":"2026-08-31T21:30:20.801153Z","steps":["trace[1823530764] 'process raft request' (duration: 107.423826ms)","trace[1823530764] 'compare' (duration: 170.004013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:30:20.801245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.084115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-ggm4w\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-31T21:30:20.801282Z","caller":"traceutil/trace.go:171","msg":"trace[1648558766] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-ggm4w; range_end:; response_count:1; response_revision:5572; }","duration":"245.118786ms","start":"2026-08-31T21:30:20.556148Z","end":"2026-08-31T21:30:20.801267Z","steps":["trace[1648558766] 'agreement among raft nodes before linearized reading' (duration: 244.92312ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:20.967925Z","caller":"traceutil/trace.go:171","msg":"trace[710060475] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"247.64762ms","start":"2026-08-31T21:30:20.720247Z","end":"2026-08-31T21:30:20.967894Z","steps":["trace[710060475] 'process raft request' (duration: 222.731203ms)","trace[710060475] 'compare' (duration: 24.775973ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:30:20.972219Z","caller":"traceutil/trace.go:171","msg":"trace[228383185] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"158.303751ms","start":"2026-08-31T21:30:20.813841Z","end":"2026-08-31T21:30:20.972145Z","steps":["trace[228383185] 'process raft request' (duration: 157.960781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:30:28.431693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.813609ms","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-08-31T21:30:28.431822Z","caller":"traceutil/trace.go:171","msg":"trace[1248059282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5752; }","duration":"119.009334ms","start":"2026-08-31T21:30:28.312792Z","end":"2026-08-31T21:30:28.431802Z","steps":["trace[1248059282] 'range keys from in-memory index tree' (duration: 118.594362ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:30:40.130315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-08-31T21:30:40.23258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"100.081712ms","hash":343070332,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T21:30:40.232658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":343070332,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-08-31T21:31:23.501886Z","caller":"traceutil/trace.go:171","msg":"trace[1678299686] transaction","detail":"{read_only:false; response_revision:6266; number_of_response:1; }","duration":"150.025934ms","start":"2026-08-31T21:31:23.351805Z","end":"2026-08-31T21:31:23.501831Z","steps":["trace[1678299686] 'process raft request' (duration: 103.356404ms)","trace[1678299686] 'compare' (duration: 46.461174ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:35:40.138343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5895} {"level":"info","ts":"2026-08-31T21:35:40.241074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5895,"took":"100.173082ms","hash":2107531419,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":26542080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T21:35:40.241146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2107531419,"revision":5895,"compact-revision":3004} {"level":"warn","ts":"2026-08-31T21:36:58.969371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.403604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-31T21:36:58.969493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.528149ms","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-08-31T21:36:58.969497Z","caller":"traceutil/trace.go:171","msg":"trace[1737451152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8693; }","duration":"274.596339ms","start":"2026-08-31T21:36:58.694874Z","end":"2026-08-31T21:36:58.96947Z","steps":["trace[1737451152] 'range keys from in-memory index tree' (duration: 274.152696ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:58.969567Z","caller":"traceutil/trace.go:171","msg":"trace[986763430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8693; }","duration":"261.679423ms","start":"2026-08-31T21:36:58.707871Z","end":"2026-08-31T21:36:58.969551Z","steps":["trace[986763430] 'range keys from in-memory index tree' (duration: 261.136717ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:58.969723Z","caller":"traceutil/trace.go:171","msg":"trace[464676521] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"250.198328ms","start":"2026-08-31T21:36:58.719451Z","end":"2026-08-31T21:36:58.969649Z","steps":["trace[464676521] 'process raft request' (duration: 156.156795ms)","trace[464676521] 'compare' (duration: 93.737304ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:36:58.972284Z","caller":"traceutil/trace.go:171","msg":"trace[1883591379] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8996; }","duration":"116.804507ms","start":"2026-08-31T21:36:58.855459Z","end":"2026-08-31T21:36:58.972264Z","steps":["trace[1883591379] 'read index received' (duration: 20.176598ms)","trace[1883591379] 'applied index is now lower than readState.Index' (duration: 96.626729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:36:58.972725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.266151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T21:36:58.972769Z","caller":"traceutil/trace.go:171","msg":"trace[412326445] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8694; }","duration":"117.348103ms","start":"2026-08-31T21:36:58.855407Z","end":"2026-08-31T21:36:58.972756Z","steps":["trace[412326445] 'agreement among raft nodes before linearized reading' (duration: 116.930961ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:59.123382Z","caller":"traceutil/trace.go:171","msg":"trace[2079825506] linearizableReadLoop","detail":"{readStateIndex:9000; appliedIndex:8998; }","duration":"144.392851ms","start":"2026-08-31T21:36:58.978948Z","end":"2026-08-31T21:36:59.123341Z","steps":["trace[2079825506] 'read index received' (duration: 45.377483ms)","trace[2079825506] 'applied index is now lower than readState.Index' (duration: 99.013528ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:36:59.123568Z","caller":"traceutil/trace.go:171","msg":"trace[727709580] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"148.006767ms","start":"2026-08-31T21:36:58.975547Z","end":"2026-08-31T21:36:59.123554Z","steps":["trace[727709580] 'process raft request' (duration: 145.353499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:36:59.123922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.948077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T21:36:59.123957Z","caller":"traceutil/trace.go:171","msg":"trace[1145761847] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8696; }","duration":"145.03861ms","start":"2026-08-31T21:36:58.978908Z","end":"2026-08-31T21:36:59.123946Z","steps":["trace[1145761847] 'agreement among raft nodes before linearized reading' (duration: 144.820384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:36:59.123975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.813163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lk4tp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-31T21:36:59.124063Z","caller":"traceutil/trace.go:171","msg":"trace[290089387] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lk4tp; range_end:; response_count:1; response_revision:8696; }","duration":"133.951517ms","start":"2026-08-31T21:36:58.99009Z","end":"2026-08-31T21:36:59.124041Z","steps":["trace[290089387] 'agreement among raft nodes before linearized reading' (duration: 133.688829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:36:59.124091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.13831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:36:59.124224Z","caller":"traceutil/trace.go:171","msg":"trace[175404696] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8696; }","duration":"101.301804ms","start":"2026-08-31T21:36:59.022867Z","end":"2026-08-31T21:36:59.124169Z","steps":["trace[175404696] 'agreement among raft nodes before linearized reading' (duration: 101.128519ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:11.625342Z","caller":"traceutil/trace.go:171","msg":"trace[1618882880] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"124.843842ms","start":"2026-08-31T21:37:11.500463Z","end":"2026-08-31T21:37:11.625306Z","steps":["trace[1618882880] 'process raft request' (duration: 117.873649ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:12.701738Z","caller":"traceutil/trace.go:171","msg":"trace[459207669] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"187.753277ms","start":"2026-08-31T21:37:12.513951Z","end":"2026-08-31T21:37:12.701705Z","steps":["trace[459207669] 'process raft request' (duration: 187.52915ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:12.722122Z","caller":"traceutil/trace.go:171","msg":"trace[1096128724] linearizableReadLoop","detail":"{readStateIndex:9069; appliedIndex:9067; }","duration":"157.571336ms","start":"2026-08-31T21:37:12.564523Z","end":"2026-08-31T21:37:12.722094Z","steps":["trace[1096128724] 'read index received' (duration: 137.058238ms)","trace[1096128724] 'applied index is now lower than readState.Index' (duration: 20.511928ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:37:12.722246Z","caller":"traceutil/trace.go:171","msg":"trace[155330286] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"131.789183ms","start":"2026-08-31T21:37:12.590447Z","end":"2026-08-31T21:37:12.722236Z","steps":["trace[155330286] 'process raft request' (duration: 131.606368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:12.722493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.955957ms","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-08-31T21:37:12.722547Z","caller":"traceutil/trace.go:171","msg":"trace[314625441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8764; }","duration":"158.0703ms","start":"2026-08-31T21:37:12.564461Z","end":"2026-08-31T21:37:12.722531Z","steps":["trace[314625441] 'agreement among raft nodes before linearized reading' (duration: 157.891905ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:12.722328Z","caller":"traceutil/trace.go:171","msg":"trace[1544347260] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"205.03676ms","start":"2026-08-31T21:37:12.517234Z","end":"2026-08-31T21:37:12.722271Z","steps":["trace[1544347260] 'process raft request' (duration: 204.712661ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:16.756784Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238e.wal"} {"level":"info","ts":"2026-08-31T21:37:41.683737Z","caller":"traceutil/trace.go:171","msg":"trace[1459846714] linearizableReadLoop","detail":"{readStateIndex:9286; appliedIndex:9285; }","duration":"487.643172ms","start":"2026-08-31T21:37:41.196067Z","end":"2026-08-31T21:37:41.68371Z","steps":["trace[1459846714] 'read index received' (duration: 487.467497ms)","trace[1459846714] 'applied index is now lower than readState.Index' (duration: 174.565µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:37:41.683869Z","caller":"traceutil/trace.go:171","msg":"trace[562804021] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"534.234912ms","start":"2026-08-31T21:37:41.149621Z","end":"2026-08-31T21:37:41.683856Z","steps":["trace[562804021] 'process raft request' (duration: 533.938583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:41.683992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:37:41.149589Z","time spent":"534.304684ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:37:41.684042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.691181ms","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-08-31T21:37:41.684093Z","caller":"traceutil/trace.go:171","msg":"trace[828222424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8971; }","duration":"388.782174ms","start":"2026-08-31T21:37:41.295298Z","end":"2026-08-31T21:37:41.68408Z","steps":["trace[828222424] 'agreement among raft nodes before linearized reading' (duration: 388.62465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:41.684125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:37:41.295279Z","time spent":"388.837816ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T21:37:41.68426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.203277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:37:41.68429Z","caller":"traceutil/trace.go:171","msg":"trace[581857829] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8971; }","duration":"488.2754ms","start":"2026-08-31T21:37:41.196Z","end":"2026-08-31T21:37:41.684275Z","steps":["trace[581857829] 'agreement among raft nodes before linearized reading' (duration: 488.221059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:41.684315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:37:41.195978Z","time spent":"488.328871ms","remote":"127.0.0.1:44708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-08-31T21:37:41.684355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.087418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lk4tp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-31T21:37:41.684393Z","caller":"traceutil/trace.go:171","msg":"trace[339390252] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lk4tp; range_end:; response_count:1; response_revision:8971; }","duration":"244.14664ms","start":"2026-08-31T21:37:41.440236Z","end":"2026-08-31T21:37:41.684382Z","steps":["trace[339390252] 'agreement among raft nodes before linearized reading' (duration: 244.034547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:41.684512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.738387ms","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-08-31T21:37:41.684537Z","caller":"traceutil/trace.go:171","msg":"trace[865816529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8971; }","duration":"295.798459ms","start":"2026-08-31T21:37:41.388731Z","end":"2026-08-31T21:37:41.684529Z","steps":["trace[865816529] 'agreement among raft nodes before linearized reading' (duration: 295.719016ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:44.749412Z","caller":"traceutil/trace.go:171","msg":"trace[1900094506] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"213.34224ms","start":"2026-08-31T21:37:44.536043Z","end":"2026-08-31T21:37:44.749386Z","steps":["trace[1900094506] 'process raft request' (duration: 213.183696ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:53.215481Z","caller":"traceutil/trace.go:171","msg":"trace[1021009870] linearizableReadLoop","detail":"{readStateIndex:9349; appliedIndex:9348; }","duration":"194.053407ms","start":"2026-08-31T21:37:53.021395Z","end":"2026-08-31T21:37:53.215448Z","steps":["trace[1021009870] 'read index received' (duration: 193.681516ms)","trace[1021009870] 'applied index is now lower than readState.Index' (duration: 370.291µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:37:53.215846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.829999ms","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-08-31T21:37:53.215886Z","caller":"traceutil/trace.go:171","msg":"trace[721496416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9032; }","duration":"182.919802ms","start":"2026-08-31T21:37:53.032954Z","end":"2026-08-31T21:37:53.215874Z","steps":["trace[721496416] 'agreement among raft nodes before linearized reading' (duration: 182.710486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:53.215905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.460889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T21:37:53.216028Z","caller":"traceutil/trace.go:171","msg":"trace[1985604689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9032; }","duration":"194.658725ms","start":"2026-08-31T21:37:53.021339Z","end":"2026-08-31T21:37:53.215997Z","steps":["trace[1985604689] 'agreement among raft nodes before linearized reading' (duration: 194.273823ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:53.21602Z","caller":"traceutil/trace.go:171","msg":"trace[983508457] transaction","detail":"{read_only:false; response_revision:9032; number_of_response:1; }","duration":"222.801596ms","start":"2026-08-31T21:37:52.992769Z","end":"2026-08-31T21:37:53.21557Z","steps":["trace[983508457] 'process raft request' (duration: 222.449026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:53.43122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.658575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:37:53.431462Z","caller":"traceutil/trace.go:171","msg":"trace[420205396] transaction","detail":"{read_only:false; response_revision:9033; number_of_response:1; }","duration":"210.393424ms","start":"2026-08-31T21:37:53.221041Z","end":"2026-08-31T21:37:53.431435Z","steps":["trace[420205396] 'process raft request' (duration: 65.353179ms)","trace[420205396] 'compare' (duration: 144.50573ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:37:53.431854Z","caller":"traceutil/trace.go:171","msg":"trace[1938701108] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"104.080849ms","start":"2026-08-31T21:37:53.327738Z","end":"2026-08-31T21:37:53.431819Z","steps":["trace[1938701108] 'process raft request' (duration: 103.977856ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:53.431925Z","caller":"traceutil/trace.go:171","msg":"trace[1906721] transaction","detail":"{read_only:false; response_revision:9034; number_of_response:1; }","duration":"208.928731ms","start":"2026-08-31T21:37:53.222942Z","end":"2026-08-31T21:37:53.431871Z","steps":["trace[1906721] 'process raft request' (duration: 208.424836ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:53.932961Z","caller":"traceutil/trace.go:171","msg":"trace[311767145] transaction","detail":"{read_only:false; response_revision:9039; number_of_response:1; }","duration":"144.205582ms","start":"2026-08-31T21:37:53.788708Z","end":"2026-08-31T21:37:53.932914Z","steps":["trace[311767145] 'process raft request' (duration: 116.925655ms)","trace[311767145] 'compare' (duration: 26.978408ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:37:53.954154Z","caller":"traceutil/trace.go:171","msg":"trace[337313756] transaction","detail":"{read_only:false; response_revision:9040; number_of_response:1; }","duration":"165.116821ms","start":"2026-08-31T21:37:53.788987Z","end":"2026-08-31T21:37:53.954104Z","steps":["trace[337313756] 'process raft request' (duration: 164.906205ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:53.954306Z","caller":"traceutil/trace.go:171","msg":"trace[351520968] linearizableReadLoop","detail":"{readStateIndex:9358; appliedIndex:9356; }","duration":"164.695299ms","start":"2026-08-31T21:37:53.78958Z","end":"2026-08-31T21:37:53.954275Z","steps":["trace[351520968] 'read index received' (duration: 116.072659ms)","trace[351520968] 'applied index is now lower than readState.Index' (duration: 48.62025ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:37:53.954414Z","caller":"traceutil/trace.go:171","msg":"trace[1025360580] transaction","detail":"{read_only:false; response_revision:9041; number_of_response:1; }","duration":"117.898053ms","start":"2026-08-31T21:37:53.836473Z","end":"2026-08-31T21:37:53.954371Z","steps":["trace[1025360580] 'process raft request' (duration: 117.585084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:37:53.95486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.260226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228861"} {"level":"info","ts":"2026-08-31T21:37:53.954916Z","caller":"traceutil/trace.go:171","msg":"trace[1842719883] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9041; }","duration":"165.40467ms","start":"2026-08-31T21:37:53.789498Z","end":"2026-08-31T21:37:53.954903Z","steps":["trace[1842719883] 'agreement among raft nodes before linearized reading' (duration: 164.886825ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:38:57.625594Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3e8b7bea2e3a32b","local-member-applied-index":10006,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T21:38:57.640693Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10006} {"level":"info","ts":"2026-08-31T21:38:57.640845Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5006} {"level":"info","ts":"2026-08-31T21:39:26.003979Z","caller":"traceutil/trace.go:171","msg":"trace[575478602] transaction","detail":"{read_only:false; response_revision:9908; number_of_response:1; }","duration":"190.08645ms","start":"2026-08-31T21:39:25.813862Z","end":"2026-08-31T21:39:26.003949Z","steps":["trace[575478602] 'process raft request' (duration: 189.858563ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:40:40.146961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8047} {"level":"info","ts":"2026-08-31T21:40:40.21881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8047,"took":"68.913966ms","hash":844842443,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T21:40:40.218914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":844842443,"revision":8047,"compact-revision":5895} {"level":"warn","ts":"2026-08-31T21:45:09.196454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.116756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-31T21:45:09.364941Z","caller":"traceutil/trace.go:171","msg":"trace[920718236] linearizableReadLoop","detail":"{readStateIndex:12792; appliedIndex:12791; }","duration":"121.190849ms","start":"2026-08-31T21:45:09.243714Z","end":"2026-08-31T21:45:09.364905Z","steps":["trace[920718236] 'read index received' (duration: 120.615442ms)","trace[920718236] 'applied index is now lower than readState.Index' (duration: 573.277µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:45:09.36502Z","caller":"traceutil/trace.go:171","msg":"trace[1558254077] transaction","detail":"{read_only:false; response_revision:12369; number_of_response:1; }","duration":"147.885874ms","start":"2026-08-31T21:45:09.217079Z","end":"2026-08-31T21:45:09.364965Z","steps":["trace[1558254077] 'process raft request' (duration: 147.490492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:45:09.365228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.478758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T21:45:09.365301Z","caller":"traceutil/trace.go:171","msg":"trace[1493799786] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:12369; }","duration":"121.635592ms","start":"2026-08-31T21:45:09.243649Z","end":"2026-08-31T21:45:09.365284Z","steps":["trace[1493799786] 'agreement among raft nodes before linearized reading' (duration: 121.444366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:45:10.296946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.025357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T21:45:10.297045Z","caller":"traceutil/trace.go:171","msg":"trace[609883818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12373; }","duration":"260.197972ms","start":"2026-08-31T21:45:10.036824Z","end":"2026-08-31T21:45:10.297022Z","steps":["trace[609883818] 'range keys from in-memory index tree' (duration: 259.769329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:45:10.297161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.770516ms","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-08-31T21:45:10.297279Z","caller":"traceutil/trace.go:171","msg":"trace[380685582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12373; }","duration":"248.925251ms","start":"2026-08-31T21:45:10.048324Z","end":"2026-08-31T21:45:10.297249Z","steps":["trace[380685582] 'range keys from in-memory index tree' (duration: 248.56032ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:45:40.156276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10412} {"level":"info","ts":"2026-08-31T21:45:40.250942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10412,"took":"91.142175ms","hash":3155699849,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":25731072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T21:45:40.251019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155699849,"revision":10412,"compact-revision":8047} {"level":"info","ts":"2026-08-31T21:50:40.164797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12583} {"level":"info","ts":"2026-08-31T21:50:40.252852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12583,"took":"84.946663ms","hash":508691032,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T21:50:40.252924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508691032,"revision":12583,"compact-revision":10412} {"level":"info","ts":"2026-08-31T21:51:55.014588Z","caller":"traceutil/trace.go:171","msg":"trace[345328427] transaction","detail":"{read_only:false; response_revision:15052; number_of_response:1; }","duration":"158.35902ms","start":"2026-08-31T21:51:54.856188Z","end":"2026-08-31T21:51:55.014547Z","steps":["trace[345328427] 'process raft request' (duration: 156.547797ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:03.591461Z","caller":"traceutil/trace.go:171","msg":"trace[1020371597] transaction","detail":"{read_only:false; response_revision:15110; number_of_response:1; }","duration":"144.598358ms","start":"2026-08-31T21:52:03.446794Z","end":"2026-08-31T21:52:03.591392Z","steps":["trace[1020371597] 'process raft request' (duration: 144.369392ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:04.565657Z","caller":"traceutil/trace.go:171","msg":"trace[1121180937] linearizableReadLoop","detail":"{readStateIndex:15637; appliedIndex:15636; }","duration":"169.605539ms","start":"2026-08-31T21:52:04.396011Z","end":"2026-08-31T21:52:04.565616Z","steps":["trace[1121180937] 'read index received' (duration: 169.308161ms)","trace[1121180937] 'applied index is now lower than readState.Index' (duration: 296.308µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:52:04.565714Z","caller":"traceutil/trace.go:171","msg":"trace[424672050] transaction","detail":"{read_only:false; response_revision:15115; number_of_response:1; }","duration":"489.175892ms","start":"2026-08-31T21:52:04.076495Z","end":"2026-08-31T21:52:04.565671Z","steps":["trace[424672050] 'process raft request' (duration: 488.910194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:04.565914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:52:04.076455Z","time spent":"489.340657ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:52:04.565917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.872937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T21:52:04.565978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.146889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T21:52:04.566049Z","caller":"traceutil/trace.go:171","msg":"trace[500018567] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15115; }","duration":"163.344425ms","start":"2026-08-31T21:52:04.402687Z","end":"2026-08-31T21:52:04.566031Z","steps":["trace[500018567] 'agreement among raft nodes before linearized reading' (duration: 163.14876ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:04.566048Z","caller":"traceutil/trace.go:171","msg":"trace[1663214269] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:15115; }","duration":"170.030791ms","start":"2026-08-31T21:52:04.39599Z","end":"2026-08-31T21:52:04.566021Z","steps":["trace[1663214269] 'agreement among raft nodes before linearized reading' (duration: 169.806775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:15.327667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.157374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:52:15.32799Z","caller":"traceutil/trace.go:171","msg":"trace[204419019] transaction","detail":"{read_only:false; response_revision:15285; number_of_response:1; }","duration":"421.108052ms","start":"2026-08-31T21:52:14.906826Z","end":"2026-08-31T21:52:15.327934Z","steps":["trace[204419019] 'process raft request' (duration: 79.530735ms)","trace[204419019] 'compare' (duration: 340.856096ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:52:15.328071Z","caller":"traceutil/trace.go:171","msg":"trace[133994418] transaction","detail":"{read_only:false; response_revision:15288; number_of_response:1; }","duration":"229.85087ms","start":"2026-08-31T21:52:15.098197Z","end":"2026-08-31T21:52:15.328048Z","steps":["trace[133994418] 'process raft request' (duration: 229.789278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:15.328179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:52:14.906806Z","time spent":"421.265916ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:52:15.328287Z","caller":"traceutil/trace.go:171","msg":"trace[38526149] transaction","detail":"{read_only:false; response_revision:15286; number_of_response:1; }","duration":"421.3903ms","start":"2026-08-31T21:52:14.906862Z","end":"2026-08-31T21:52:15.328252Z","steps":["trace[38526149] 'process raft request' (duration: 420.943007ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:15.328336Z","caller":"traceutil/trace.go:171","msg":"trace[2654061] transaction","detail":"{read_only:false; response_revision:15287; number_of_response:1; }","duration":"421.021599ms","start":"2026-08-31T21:52:14.907303Z","end":"2026-08-31T21:52:15.328324Z","steps":["trace[2654061] 'process raft request' (duration: 420.615827ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:15.32834Z","caller":"traceutil/trace.go:171","msg":"trace[129245263] linearizableReadLoop","detail":"{readStateIndex:15812; appliedIndex:15809; }","duration":"311.509208ms","start":"2026-08-31T21:52:15.016796Z","end":"2026-08-31T21:52:15.328306Z","steps":["trace[129245263] 'read index received' (duration: 261.211817ms)","trace[129245263] 'applied index is now lower than readState.Index' (duration: 50.294211ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:52:15.328392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:52:14.907291Z","time spent":"421.07152ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:52:15.328466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:52:14.906821Z","time spent":"421.540604ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:52:15.328502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.708383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-w4z9d.18d1025edbf69f8a\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-08-31T21:52:15.328538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.963994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:52:15.328563Z","caller":"traceutil/trace.go:171","msg":"trace[1146717656] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-w4z9d.18d1025edbf69f8a; range_end:; response_count:1; response_revision:15288; }","duration":"311.828487ms","start":"2026-08-31T21:52:15.016714Z","end":"2026-08-31T21:52:15.328542Z","steps":["trace[1146717656] 'agreement among raft nodes before linearized reading' (duration: 311.721603ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:15.328566Z","caller":"traceutil/trace.go:171","msg":"trace[1902239427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15288; }","duration":"150.033576ms","start":"2026-08-31T21:52:15.178522Z","end":"2026-08-31T21:52:15.328556Z","steps":["trace[1902239427] 'agreement among raft nodes before linearized reading' (duration: 149.974184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:15.328596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:52:15.016684Z","time spent":"311.904079ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":807,"request content":"key:\"/registry/events/openstack/ovn-controller-default-w4z9d.18d1025edbf69f8a\" "} {"level":"info","ts":"2026-08-31T21:53:24.161035Z","caller":"traceutil/trace.go:171","msg":"trace[2018426646] transaction","detail":"{read_only:false; response_revision:16217; number_of_response:1; }","duration":"112.089456ms","start":"2026-08-31T21:53:24.048906Z","end":"2026-08-31T21:53:24.160995Z","steps":["trace[2018426646] 'process raft request' (duration: 111.820928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.152869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.454133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T21:53:58.152968Z","caller":"traceutil/trace.go:171","msg":"trace[1736369434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16390; }","duration":"221.566746ms","start":"2026-08-31T21:53:57.931381Z","end":"2026-08-31T21:53:58.152948Z","steps":["trace[1736369434] 'range keys from in-memory index tree' (duration: 221.294928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.153582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.104952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-31T21:53:58.153642Z","caller":"traceutil/trace.go:171","msg":"trace[604096797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16390; }","duration":"222.215045ms","start":"2026-08-31T21:53:57.931411Z","end":"2026-08-31T21:53:58.153626Z","steps":["trace[604096797] 'range keys from in-memory index tree' (duration: 221.940017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.153915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.289943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-31T21:53:58.154287Z","caller":"traceutil/trace.go:171","msg":"trace[1454335622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16390; }","duration":"214.730746ms","start":"2026-08-31T21:53:57.939531Z","end":"2026-08-31T21:53:58.154262Z","steps":["trace[1454335622] 'range keys from in-memory index tree' (duration: 214.147239ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:53:58.361279Z","caller":"traceutil/trace.go:171","msg":"trace[405193528] linearizableReadLoop","detail":"{readStateIndex:16954; appliedIndex:16951; }","duration":"196.421881ms","start":"2026-08-31T21:53:58.164814Z","end":"2026-08-31T21:53:58.361236Z","steps":["trace[405193528] 'read index received' (duration: 83.211232ms)","trace[405193528] 'applied index is now lower than readState.Index' (duration: 113.206809ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:53:58.36131Z","caller":"traceutil/trace.go:171","msg":"trace[842119197] transaction","detail":"{read_only:false; response_revision:16393; number_of_response:1; }","duration":"196.632067ms","start":"2026-08-31T21:53:58.164641Z","end":"2026-08-31T21:53:58.361273Z","steps":["trace[842119197] 'process raft request' (duration: 196.338909ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:53:58.361334Z","caller":"traceutil/trace.go:171","msg":"trace[1083766041] transaction","detail":"{read_only:false; response_revision:16392; number_of_response:1; }","duration":"201.52675ms","start":"2026-08-31T21:53:58.159516Z","end":"2026-08-31T21:53:58.361042Z","steps":["trace[1083766041] 'process raft request' (duration: 201.252552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.362347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.514513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769771"} {"level":"info","ts":"2026-08-31T21:53:58.362411Z","caller":"traceutil/trace.go:171","msg":"trace[1440109223] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16393; }","duration":"197.634237ms","start":"2026-08-31T21:53:58.164761Z","end":"2026-08-31T21:53:58.362396Z","steps":["trace[1440109223] 'agreement among raft nodes before linearized reading' (duration: 196.653828ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:53:58.465233Z","caller":"traceutil/trace.go:171","msg":"trace[1836374545] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"158.522773ms","start":"2026-08-31T21:53:58.306669Z","end":"2026-08-31T21:53:58.465192Z","steps":["trace[1836374545] 'process raft request' (duration: 156.69903ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:53:58.465314Z","caller":"traceutil/trace.go:171","msg":"trace[1172353461] linearizableReadLoop","detail":"{readStateIndex:16955; appliedIndex:16954; }","duration":"103.896617ms","start":"2026-08-31T21:53:58.361387Z","end":"2026-08-31T21:53:58.465284Z","steps":["trace[1172353461] 'read index received' (duration: 101.980731ms)","trace[1172353461] 'applied index is now lower than readState.Index' (duration: 1.912516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:53:58.465517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.354558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T21:53:58.465581Z","caller":"traceutil/trace.go:171","msg":"trace[1932553155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16396; }","duration":"288.478281ms","start":"2026-08-31T21:53:58.177087Z","end":"2026-08-31T21:53:58.465566Z","steps":["trace[1932553155] 'agreement among raft nodes before linearized reading' (duration: 288.321737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.465753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.317135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T21:53:58.465826Z","caller":"traceutil/trace.go:171","msg":"trace[1347650554] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16396; }","duration":"136.466079ms","start":"2026-08-31T21:53:58.329339Z","end":"2026-08-31T21:53:58.465805Z","steps":["trace[1347650554] 'agreement among raft nodes before linearized reading' (duration: 136.033967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.466471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.128932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769771"} {"level":"info","ts":"2026-08-31T21:53:58.466521Z","caller":"traceutil/trace.go:171","msg":"trace[1194825698] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16396; }","duration":"300.215825ms","start":"2026-08-31T21:53:58.166293Z","end":"2026-08-31T21:53:58.466509Z","steps":["trace[1194825698] 'agreement among raft nodes before linearized reading' (duration: 299.217396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:53:58.466603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:53:58.166279Z","time spent":"300.309218ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769795,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T21:53:58.466653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.310094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T21:53:58.466813Z","caller":"traceutil/trace.go:171","msg":"trace[1627721193] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16396; }","duration":"137.518699ms","start":"2026-08-31T21:53:58.329273Z","end":"2026-08-31T21:53:58.466791Z","steps":["trace[1627721193] 'agreement among raft nodes before linearized reading' (duration: 136.066647ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:55:40.182178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14584} {"level":"info","ts":"2026-08-31T21:55:40.265922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14584,"took":"80.140782ms","hash":1503425088,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T21:55:40.265984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1503425088,"revision":14584,"compact-revision":12583} {"level":"info","ts":"2026-08-31T21:59:38.283228Z","caller":"traceutil/trace.go:171","msg":"trace[1314766943] linearizableReadLoop","detail":"{readStateIndex:19462; appliedIndex:19461; }","duration":"104.551464ms","start":"2026-08-31T21:59:38.178639Z","end":"2026-08-31T21:59:38.28319Z","steps":["trace[1314766943] 'read index received' (duration: 104.177243ms)","trace[1314766943] 'applied index is now lower than readState.Index' (duration: 373.221µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:59:38.283648Z","caller":"traceutil/trace.go:171","msg":"trace[1312799184] transaction","detail":"{read_only:false; response_revision:18820; number_of_response:1; }","duration":"231.660069ms","start":"2026-08-31T21:59:38.051937Z","end":"2026-08-31T21:59:38.283597Z","steps":["trace[1312799184] 'process raft request' (duration: 231.048451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:38.283985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T21:59:38.284034Z","caller":"traceutil/trace.go:171","msg":"trace[1157370676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18820; }","duration":"105.45334ms","start":"2026-08-31T21:59:38.178567Z","end":"2026-08-31T21:59:38.28402Z","steps":["trace[1157370676] 'agreement among raft nodes before linearized reading' (duration: 105.345237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:38.706701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.893769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-31T21:59:38.706733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.120179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-31T21:59:38.706835Z","caller":"traceutil/trace.go:171","msg":"trace[1018190516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18821; }","duration":"283.097846ms","start":"2026-08-31T21:59:38.423712Z","end":"2026-08-31T21:59:38.70681Z","steps":["trace[1018190516] 'range keys from in-memory index tree' (duration: 282.709054ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:38.706894Z","caller":"traceutil/trace.go:171","msg":"trace[1396284587] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18821; }","duration":"165.334964ms","start":"2026-08-31T21:59:38.541531Z","end":"2026-08-31T21:59:38.706865Z","steps":["trace[1396284587] 'range keys from in-memory index tree' (duration: 164.889252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:38.706953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.445348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-08-31T21:59:38.707027Z","caller":"traceutil/trace.go:171","msg":"trace[410530498] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18821; }","duration":"251.516319ms","start":"2026-08-31T21:59:38.455487Z","end":"2026-08-31T21:59:38.707004Z","steps":["trace[410530498] 'range keys from in-memory index tree' (duration: 251.353195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:38.707159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.973237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-31T21:59:38.706772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.749419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-31T21:59:38.707292Z","caller":"traceutil/trace.go:171","msg":"trace[1692596908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18821; }","duration":"304.380709ms","start":"2026-08-31T21:59:38.402882Z","end":"2026-08-31T21:59:38.707263Z","steps":["trace[1692596908] 'range keys from in-memory index tree' (duration: 303.71526ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:38.707287Z","caller":"traceutil/trace.go:171","msg":"trace[1119162148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18821; }","duration":"277.317396ms","start":"2026-08-31T21:59:38.429957Z","end":"2026-08-31T21:59:38.707275Z","steps":["trace[1119162148] 'range keys from in-memory index tree' (duration: 276.597725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:38.707495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:38.402873Z","time spent":"304.604715ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T21:59:39.162214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.373781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T21:59:39.163012Z","caller":"traceutil/trace.go:171","msg":"trace[378154674] linearizableReadLoop","detail":"{readStateIndex:19466; appliedIndex:19463; }","duration":"447.772471ms","start":"2026-08-31T21:59:38.7152Z","end":"2026-08-31T21:59:39.162972Z","steps":["trace[378154674] 'read index received' (duration: 5.155801ms)","trace[378154674] 'applied index is now lower than readState.Index' (duration: 442.612259ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:59:39.16301Z","caller":"traceutil/trace.go:171","msg":"trace[280644464] transaction","detail":"{read_only:false; response_revision:18824; number_of_response:1; }","duration":"449.206203ms","start":"2026-08-31T21:59:38.713754Z","end":"2026-08-31T21:59:39.16296Z","steps":["trace[280644464] 'process raft request' (duration: 448.593995ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:39.16311Z","caller":"traceutil/trace.go:171","msg":"trace[330326907] transaction","detail":"{read_only:false; response_revision:18823; number_of_response:1; }","duration":"449.366247ms","start":"2026-08-31T21:59:38.713726Z","end":"2026-08-31T21:59:39.163092Z","steps":["trace[330326907] 'process raft request' (duration: 333.005497ms)","trace[330326907] 'compare' (duration: 115.159225ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:59:39.163231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:38.713725Z","time spent":"449.398518ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:59:39.163216Z","caller":"traceutil/trace.go:171","msg":"trace[632479046] transaction","detail":"{read_only:false; response_revision:18825; number_of_response:1; }","duration":"217.256486ms","start":"2026-08-31T21:59:38.94591Z","end":"2026-08-31T21:59:39.163166Z","steps":["trace[632479046] 'process raft request' (duration: 216.523175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:39.163335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:38.713693Z","time spent":"449.507411ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:59:39.336205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.68756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"warn","ts":"2026-08-31T21:59:39.336656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.428319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19630"} {"level":"info","ts":"2026-08-31T21:59:39.336755Z","caller":"traceutil/trace.go:171","msg":"trace[1505116699] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18825; }","duration":"621.559023ms","start":"2026-08-31T21:59:38.715171Z","end":"2026-08-31T21:59:39.33673Z","steps":["trace[1505116699] 'agreement among raft nodes before linearized reading' (duration: 448.000977ms)","trace[1505116699] 'range keys from in-memory index tree' (duration: 173.225695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:59:39.336804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:38.715154Z","time spent":"621.640476ms","remote":"127.0.0.1:44168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19654,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-31T21:59:39.351985Z","caller":"traceutil/trace.go:171","msg":"trace[1708592135] transaction","detail":"{read_only:false; response_revision:18826; number_of_response:1; }","duration":"120.82826ms","start":"2026-08-31T21:59:39.231122Z","end":"2026-08-31T21:59:39.35195Z","steps":["trace[1708592135] 'process raft request' (duration: 120.622504ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:00:24.372837Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3e8b7bea2e3a32b","local-member-applied-index":20007,"local-member-snapshot-index":10006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T22:00:24.458787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-08-31T22:00:24.458945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-08-31T22:00:40.19098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17047} {"level":"info","ts":"2026-08-31T22:00:40.297305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17047,"took":"101.670479ms","hash":3922021058,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":32628736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-31T22:00:40.297381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3922021058,"revision":17047,"compact-revision":14584} {"level":"info","ts":"2026-08-31T22:05:40.198159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19487} {"level":"info","ts":"2026-08-31T22:05:40.320629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19487,"took":"118.267299ms","hash":1039284230,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T22:05:40.320719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1039284230,"revision":19487,"compact-revision":17047} {"level":"info","ts":"2026-08-31T22:06:16.897791Z","caller":"traceutil/trace.go:171","msg":"trace[678680044] transaction","detail":"{read_only:false; response_revision:21957; number_of_response:1; }","duration":"110.316836ms","start":"2026-08-31T22:06:16.787442Z","end":"2026-08-31T22:06:16.897759Z","steps":["trace[678680044] 'process raft request' (duration: 110.136451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:17.196173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.055187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T22:06:17.196298Z","caller":"traceutil/trace.go:171","msg":"trace[184602196] transaction","detail":"{read_only:false; response_revision:21958; number_of_response:1; }","duration":"257.537524ms","start":"2026-08-31T22:06:16.938735Z","end":"2026-08-31T22:06:17.196273Z","steps":["trace[184602196] 'process raft request' (duration: 104.26241ms)","trace[184602196] 'compare' (duration: 152.611594ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:06:33.241386Z","caller":"traceutil/trace.go:171","msg":"trace[1114128377] linearizableReadLoop","detail":"{readStateIndex:22803; appliedIndex:22802; }","duration":"250.398435ms","start":"2026-08-31T22:06:32.990964Z","end":"2026-08-31T22:06:33.241363Z","steps":["trace[1114128377] 'read index received' (duration: 250.167858ms)","trace[1114128377] 'applied index is now lower than readState.Index' (duration: 229.397µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:06:33.241672Z","caller":"traceutil/trace.go:171","msg":"trace[1204304052] transaction","detail":"{read_only:false; response_revision:22056; number_of_response:1; }","duration":"324.142671ms","start":"2026-08-31T22:06:32.917521Z","end":"2026-08-31T22:06:33.241664Z","steps":["trace[1204304052] 'process raft request' (duration: 323.687088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:33.241769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:32.917489Z","time spent":"324.209023ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2609,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:33.241988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.031753ms","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-08-31T22:06:33.242013Z","caller":"traceutil/trace.go:171","msg":"trace[2072299275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22056; }","duration":"251.093325ms","start":"2026-08-31T22:06:32.990912Z","end":"2026-08-31T22:06:33.242005Z","steps":["trace[2072299275] 'agreement among raft nodes before linearized reading' (duration: 250.991622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:33.564022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.543304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T22:06:33.564155Z","caller":"traceutil/trace.go:171","msg":"trace[340908188] transaction","detail":"{read_only:false; response_revision:22057; number_of_response:1; }","duration":"315.057546ms","start":"2026-08-31T22:06:33.249073Z","end":"2026-08-31T22:06:33.56413Z","steps":["trace[340908188] 'process raft request' (duration: 178.309686ms)","trace[340908188] 'compare' (duration: 136.417231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T22:06:33.564584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.249032Z","time spent":"315.366125ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T22:06:33.661044Z","caller":"traceutil/trace.go:171","msg":"trace[1469716373] linearizableReadLoop","detail":"{readStateIndex:22806; appliedIndex:22803; }","duration":"324.237724ms","start":"2026-08-31T22:06:33.33677Z","end":"2026-08-31T22:06:33.661008Z","steps":["trace[1469716373] 'read index received' (duration: 90.61292ms)","trace[1469716373] 'applied index is now lower than readState.Index' (duration: 233.622834ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:06:33.661142Z","caller":"traceutil/trace.go:171","msg":"trace[451198834] transaction","detail":"{read_only:false; response_revision:22058; number_of_response:1; }","duration":"410.810887ms","start":"2026-08-31T22:06:33.250273Z","end":"2026-08-31T22:06:33.661084Z","steps":["trace[451198834] 'process raft request' (duration: 410.448247ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.661411Z","caller":"traceutil/trace.go:171","msg":"trace[107564652] transaction","detail":"{read_only:false; response_revision:22059; number_of_response:1; }","duration":"401.023911ms","start":"2026-08-31T22:06:33.260142Z","end":"2026-08-31T22:06:33.661166Z","steps":["trace[107564652] 'process raft request' (duration: 400.788714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:33.661512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.621956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-31T22:06:33.662079Z","caller":"traceutil/trace.go:171","msg":"trace[1324658317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22059; }","duration":"325.308366ms","start":"2026-08-31T22:06:33.336709Z","end":"2026-08-31T22:06:33.662018Z","steps":["trace[1324658317] 'agreement among raft nodes before linearized reading' (duration: 324.43596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:33.661601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.250246Z","time spent":"411.099785ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:33.662151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.336688Z","time spent":"325.44687ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T22:06:33.661689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.260122Z","time spent":"401.35877ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:33.90002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.302148ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-08-31T22:06:33.915952Z","caller":"traceutil/trace.go:171","msg":"trace[1134809582] transaction","detail":"{read_only:false; response_revision:22060; number_of_response:1; }","duration":"247.349566ms","start":"2026-08-31T22:06:33.66857Z","end":"2026-08-31T22:06:33.91592Z","steps":["trace[1134809582] 'process raft request' (duration: 247.039227ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.916208Z","caller":"traceutil/trace.go:171","msg":"trace[642061306] transaction","detail":"{read_only:false; response_revision:22062; number_of_response:1; }","duration":"220.48167ms","start":"2026-08-31T22:06:33.695711Z","end":"2026-08-31T22:06:33.916193Z","steps":["trace[642061306] 'process raft request' (duration: 220.423318ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.916348Z","caller":"traceutil/trace.go:171","msg":"trace[2103716698] transaction","detail":"{read_only:false; response_revision:22061; number_of_response:1; }","duration":"244.747789ms","start":"2026-08-31T22:06:33.671563Z","end":"2026-08-31T22:06:33.916311Z","steps":["trace[2103716698] 'process raft request' (duration: 244.275685ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.952381Z","caller":"traceutil/trace.go:171","msg":"trace[2025293424] transaction","detail":"{read_only:false; response_revision:22064; number_of_response:1; }","duration":"184.918349ms","start":"2026-08-31T22:06:33.767446Z","end":"2026-08-31T22:06:33.952365Z","steps":["trace[2025293424] 'process raft request' (duration: 184.843657ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.952458Z","caller":"traceutil/trace.go:171","msg":"trace[342424131] transaction","detail":"{read_only:false; response_revision:22063; number_of_response:1; }","duration":"185.027692ms","start":"2026-08-31T22:06:33.767381Z","end":"2026-08-31T22:06:33.952409Z","steps":["trace[342424131] 'process raft request' (duration: 184.822696ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.952756Z","caller":"traceutil/trace.go:171","msg":"trace[1220038818] transaction","detail":"{read_only:false; response_revision:22065; number_of_response:1; }","duration":"157.361633ms","start":"2026-08-31T22:06:33.79535Z","end":"2026-08-31T22:06:33.952712Z","steps":["trace[1220038818] 'process raft request' (duration: 156.976112ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:33.952856Z","caller":"traceutil/trace.go:171","msg":"trace[940059250] linearizableReadLoop","detail":"{readStateIndex:22813; appliedIndex:22807; }","duration":"143.024794ms","start":"2026-08-31T22:06:33.809797Z","end":"2026-08-31T22:06:33.952822Z","steps":["trace[940059250] 'read index received' (duration: 105.945119ms)","trace[940059250] 'applied index is now lower than readState.Index' (duration: 37.077965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T22:06:33.953246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.430206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-31T22:06:33.953322Z","caller":"traceutil/trace.go:171","msg":"trace[438830986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22066; }","duration":"143.557679ms","start":"2026-08-31T22:06:33.809746Z","end":"2026-08-31T22:06:33.953304Z","steps":["trace[438830986] 'agreement among raft nodes before linearized reading' (duration: 143.307582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:34.267554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.837081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T22:06:34.267715Z","caller":"traceutil/trace.go:171","msg":"trace[1034112273] linearizableReadLoop","detail":"{readStateIndex:22815; appliedIndex:22814; }","duration":"314.736676ms","start":"2026-08-31T22:06:33.952946Z","end":"2026-08-31T22:06:34.267683Z","steps":["trace[1034112273] 'read index received' (duration: 149.701489ms)","trace[1034112273] 'applied index is now lower than readState.Index' (duration: 165.032657ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:06:34.267807Z","caller":"traceutil/trace.go:171","msg":"trace[1638837400] transaction","detail":"{read_only:false; response_revision:22067; number_of_response:1; }","duration":"342.648923ms","start":"2026-08-31T22:06:33.925118Z","end":"2026-08-31T22:06:34.267767Z","steps":["trace[1638837400] 'process raft request' (duration: 177.43277ms)","trace[1638837400] 'compare' (duration: 164.636146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T22:06:34.267992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.925104Z","time spent":"342.782397ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2561,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:34.268138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.408463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-31T22:06:34.268212Z","caller":"traceutil/trace.go:171","msg":"trace[2077974753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22067; }","duration":"413.542367ms","start":"2026-08-31T22:06:33.854651Z","end":"2026-08-31T22:06:34.268194Z","steps":["trace[2077974753] 'agreement among raft nodes before linearized reading' (duration: 413.151826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:34.268271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.854623Z","time spent":"413.63724ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-31T22:06:34.269357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.215363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-31T22:06:34.269444Z","caller":"traceutil/trace.go:171","msg":"trace[1859320190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22067; }","duration":"459.326706ms","start":"2026-08-31T22:06:33.810099Z","end":"2026-08-31T22:06:34.269425Z","steps":["trace[1859320190] 'agreement among raft nodes before linearized reading' (duration: 457.886924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:34.269523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.810088Z","time spent":"459.392708ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-31T22:06:34.277142Z","caller":"traceutil/trace.go:171","msg":"trace[2110969838] transaction","detail":"{read_only:false; response_revision:22068; number_of_response:1; }","duration":"318.729573ms","start":"2026-08-31T22:06:33.958345Z","end":"2026-08-31T22:06:34.277074Z","steps":["trace[2110969838] 'process raft request' (duration: 318.533237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:34.277269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.483164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-08-31T22:06:34.277311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.958326Z","time spent":"318.903038ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:34.277368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.471639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T22:06:34.277474Z","caller":"traceutil/trace.go:171","msg":"trace[1567266717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22068; }","duration":"100.651194ms","start":"2026-08-31T22:06:34.176801Z","end":"2026-08-31T22:06:34.277452Z","steps":["trace[1567266717] 'agreement among raft nodes before linearized reading' (duration: 100.442748ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:34.277362Z","caller":"traceutil/trace.go:171","msg":"trace[541762399] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:22068; }","duration":"321.659749ms","start":"2026-08-31T22:06:33.955682Z","end":"2026-08-31T22:06:34.277342Z","steps":["trace[541762399] 'agreement among raft nodes before linearized reading' (duration: 321.34999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:34.277557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:33.95566Z","time spent":"321.879945ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":447,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-31T22:06:34.56118Z","caller":"traceutil/trace.go:171","msg":"trace[1745684872] linearizableReadLoop","detail":"{readStateIndex:22819; appliedIndex:22816; }","duration":"282.527064ms","start":"2026-08-31T22:06:34.278585Z","end":"2026-08-31T22:06:34.561112Z","steps":["trace[1745684872] 'read index received' (duration: 15.163604ms)","trace[1745684872] 'applied index is now lower than readState.Index' (duration: 267.3617ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:06:34.561264Z","caller":"traceutil/trace.go:171","msg":"trace[38604511] transaction","detail":"{read_only:false; response_revision:22071; number_of_response:1; }","duration":"282.73813ms","start":"2026-08-31T22:06:34.278474Z","end":"2026-08-31T22:06:34.561212Z","steps":["trace[38604511] 'process raft request' (duration: 263.824417ms)","trace[38604511] 'compare' (duration: 18.491031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T22:06:34.561564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.795332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T22:06:34.561603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.349139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-31T22:06:34.561696Z","caller":"traceutil/trace.go:171","msg":"trace[436268927] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:22071; }","duration":"282.504264ms","start":"2026-08-31T22:06:34.279171Z","end":"2026-08-31T22:06:34.561675Z","steps":["trace[436268927] 'agreement among raft nodes before linearized reading' (duration: 282.244036ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:34.561706Z","caller":"traceutil/trace.go:171","msg":"trace[952317259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22071; }","duration":"283.086261ms","start":"2026-08-31T22:06:34.278559Z","end":"2026-08-31T22:06:34.561645Z","steps":["trace[952317259] 'agreement among raft nodes before linearized reading' (duration: 282.765862ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:10:40.205836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21772} {"level":"info","ts":"2026-08-31T22:10:40.32328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21772,"took":"114.228822ms","hash":1401451033,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":31940608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T22:10:40.32336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1401451033,"revision":21772,"compact-revision":19487} {"level":"info","ts":"2026-08-31T22:10:56.689923Z","caller":"traceutil/trace.go:171","msg":"trace[790668261] linearizableReadLoop","detail":"{readStateIndex:25479; appliedIndex:25478; }","duration":"320.308759ms","start":"2026-08-31T22:10:56.369591Z","end":"2026-08-31T22:10:56.6899Z","steps":["trace[790668261] 'read index received' (duration: 319.701252ms)","trace[790668261] 'applied index is now lower than readState.Index' (duration: 603.977µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:10:56.690008Z","caller":"traceutil/trace.go:171","msg":"trace[466063814] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"329.656973ms","start":"2026-08-31T22:10:56.360329Z","end":"2026-08-31T22:10:56.689986Z","steps":["trace[466063814] 'process raft request' (duration: 329.151798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:10:56.690228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:10:56.360295Z","time spent":"329.766176ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418957,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:10:56.690269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.540766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-31T22:10:56.690318Z","caller":"traceutil/trace.go:171","msg":"trace[402238553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24650; }","duration":"320.777373ms","start":"2026-08-31T22:10:56.369528Z","end":"2026-08-31T22:10:56.690306Z","steps":["trace[402238553] 'agreement among raft nodes before linearized reading' (duration: 320.470294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:10:56.69041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:10:56.369487Z","time spent":"320.908907ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-31T22:10:56.691062Z","caller":"traceutil/trace.go:171","msg":"trace[1446129736] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"200.66853ms","start":"2026-08-31T22:10:56.490352Z","end":"2026-08-31T22:10:56.69102Z","steps":["trace[1446129736] 'process raft request' (duration: 200.31889ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:12:27.209338Z","caller":"traceutil/trace.go:171","msg":"trace[1685497171] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"169.763566ms","start":"2026-08-31T22:12:27.039538Z","end":"2026-08-31T22:12:27.209302Z","steps":["trace[1685497171] 'process raft request' (duration: 169.535369ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:12:27.22434Z","caller":"traceutil/trace.go:171","msg":"trace[1477278194] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"159.913718ms","start":"2026-08-31T22:12:27.064394Z","end":"2026-08-31T22:12:27.224307Z","steps":["trace[1477278194] 'process raft request' (duration: 159.606229ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:13:03.743853Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006870.wal"} {"level":"info","ts":"2026-08-31T22:15:40.21493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24055} {"level":"info","ts":"2026-08-31T22:15:40.348201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24055,"took":"127.311553ms","hash":3870016561,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":42512384,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-31T22:15:40.348278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3870016561,"revision":24055,"compact-revision":21772} {"level":"info","ts":"2026-08-31T22:20:11.225693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3e8b7bea2e3a32b","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T22:20:11.232272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-08-31T22:20:11.232394Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008} {"level":"info","ts":"2026-08-31T22:20:40.225247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27059} {"level":"info","ts":"2026-08-31T22:20:40.373912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27059,"took":"142.535838ms","hash":162637919,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":30343168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T22:20:40.373993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":162637919,"revision":27059,"compact-revision":24055}