{"level":"warn","ts":"2026-09-01T05:15:39.516165Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T05:15:39.516278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.181: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.181:2380","--initial-cluster=instance=https://162.253.55.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.181: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-09-01T05:15:39.516378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T05:15:39.516395Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-01T05:15:39.516416Z","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-09-01T05:15:39.517287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"]} {"level":"info","ts":"2026-09-01T05:15:39.51738Z","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.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.181: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-09-01T05:15:39.522491Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.73808ms"} {"level":"info","ts":"2026-09-01T05:15:39.530088Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"865c2c1f02504fb9","cluster-id":"c3b098aaa499ad51"} {"level":"info","ts":"2026-09-01T05:15:39.53023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T05:15:39.530264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 0"} {"level":"info","ts":"2026-09-01T05:15:39.530276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 865c2c1f02504fb9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T05:15:39.530286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 1"} {"level":"info","ts":"2026-09-01T05:15:39.530338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"warn","ts":"2026-09-01T05:15:39.546786Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T05:15:39.552036Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T05:15:39.554757Z","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-09-01T05:15:39.55875Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"865c2c1f02504fb9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T05:15:39.559019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"865c2c1f02504fb9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T05:15:39.559183Z","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-09-01T05:15:39.559254Z","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-09-01T05:15:39.559267Z","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-09-01T05:15:39.560342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"info","ts":"2026-09-01T05:15:39.56091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","added-peer-id":"865c2c1f02504fb9","added-peer-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-01T05:15:39.564641Z","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-09-01T05:15:39.564778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-01T05:15:39.564857Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-01T05:15:39.56518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"865c2c1f02504fb9","initial-advertise-peer-urls":["https://162.253.55.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T05:15:39.565266Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T05:15:39.931525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T05:15:39.931589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T05:15:39.931606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgPreVoteResp from 865c2c1f02504fb9 at term 1"} {"level":"info","ts":"2026-09-01T05:15:39.931619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became candidate at term 2"} {"level":"info","ts":"2026-09-01T05:15:39.931627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgVoteResp from 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-01T05:15:39.931637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became leader at term 2"} {"level":"info","ts":"2026-09-01T05:15:39.931646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 865c2c1f02504fb9 elected leader 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-01T05:15:39.93293Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:15:39.933988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"865c2c1f02504fb9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.181:2379]}","request-path":"/0/members/865c2c1f02504fb9/attributes","cluster-id":"c3b098aaa499ad51","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T05:15:39.93401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T05:15:39.934106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T05:15:39.934477Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T05:15:39.934506Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T05:15:39.934661Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:15:39.934763Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:15:39.934795Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:15:39.937493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T05:15:39.943203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.181:2379"} {"level":"info","ts":"2026-09-01T05:16:08.105736Z","caller":"traceutil/trace.go:171","msg":"trace[44097683] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"100.864865ms","start":"2026-09-01T05:16:08.004842Z","end":"2026-09-01T05:16:08.105707Z","steps":["trace[44097683] 'process raft request' (duration: 100.472363ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:25.523178Z","caller":"traceutil/trace.go:171","msg":"trace[1322462664] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"336.427189ms","start":"2026-09-01T05:16:25.186716Z","end":"2026-09-01T05:16:25.523143Z","steps":["trace[1322462664] 'process raft request' (duration: 335.201222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:16:25.524057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:16:25.186679Z","time spent":"336.607524ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:16:26.053811Z","caller":"traceutil/trace.go:171","msg":"trace[875944797] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"478.069247ms","start":"2026-09-01T05:16:25.575713Z","end":"2026-09-01T05:16:26.053783Z","steps":["trace[875944797] 'process raft request' (duration: 477.85129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:16:26.053975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:16:25.575673Z","time spent":"478.214381ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:16:26.114449Z","caller":"traceutil/trace.go:171","msg":"trace[412264823] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"485.621931ms","start":"2026-09-01T05:16:25.62875Z","end":"2026-09-01T05:16:26.114372Z","steps":["trace[412264823] 'process raft request' (duration: 468.700457ms)","trace[412264823] 'compare' (duration: 16.721498ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:16:26.114607Z","caller":"traceutil/trace.go:171","msg":"trace[47807907] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"126.772445ms","start":"2026-09-01T05:16:25.987806Z","end":"2026-09-01T05:16:26.114579Z","steps":["trace[47807907] 'read index received' (duration: 65.748348ms)","trace[47807907] 'applied index is now lower than readState.Index' (duration: 61.021127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:16:26.11466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:16:25.628721Z","time spent":"485.840517ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T05:16:26.114842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.005532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:16:26.114976Z","caller":"traceutil/trace.go:171","msg":"trace[499316234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"127.194308ms","start":"2026-09-01T05:16:25.987757Z","end":"2026-09-01T05:16:26.114951Z","steps":["trace[499316234] 'agreement among raft nodes before linearized reading' (duration: 126.995472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:16:42.481285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.947892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T05:16:42.481438Z","caller":"traceutil/trace.go:171","msg":"trace[1100762820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"165.134047ms","start":"2026-09-01T05:16:42.316277Z","end":"2026-09-01T05:16:42.481411Z","steps":["trace[1100762820] 'range keys from in-memory index tree' (duration: 164.685174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:16:42.481285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.075836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-01T05:16:42.481534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.665755ms","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-09-01T05:16:42.481661Z","caller":"traceutil/trace.go:171","msg":"trace[1488236094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"165.467207ms","start":"2026-09-01T05:16:42.316149Z","end":"2026-09-01T05:16:42.481616Z","steps":["trace[1488236094] 'range keys from in-memory index tree' (duration: 164.922581ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:42.481705Z","caller":"traceutil/trace.go:171","msg":"trace[1317554530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"159.045387ms","start":"2026-09-01T05:16:42.32264Z","end":"2026-09-01T05:16:42.481686Z","steps":["trace[1317554530] 'range keys from in-memory index tree' (duration: 158.470569ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:42.642864Z","caller":"traceutil/trace.go:171","msg":"trace[1500171101] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"156.701336ms","start":"2026-09-01T05:16:42.486123Z","end":"2026-09-01T05:16:42.642824Z","steps":["trace[1500171101] 'process raft request' (duration: 156.474929ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:42.648584Z","caller":"traceutil/trace.go:171","msg":"trace[403786634] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"162.304783ms","start":"2026-09-01T05:16:42.486255Z","end":"2026-09-01T05:16:42.64856Z","steps":["trace[403786634] 'process raft request' (duration: 162.265562ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:42.648676Z","caller":"traceutil/trace.go:171","msg":"trace[176824683] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"162.423117ms","start":"2026-09-01T05:16:42.486204Z","end":"2026-09-01T05:16:42.648627Z","steps":["trace[176824683] 'process raft request' (duration: 162.18393ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:13.082826Z","caller":"traceutil/trace.go:171","msg":"trace[951684284] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"247.17727ms","start":"2026-09-01T05:17:12.835622Z","end":"2026-09-01T05:17:13.082799Z","steps":["trace[951684284] 'process raft request' (duration: 247.007785ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:13.084054Z","caller":"traceutil/trace.go:171","msg":"trace[1716753471] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"248.394525ms","start":"2026-09-01T05:17:12.835632Z","end":"2026-09-01T05:17:13.084026Z","steps":["trace[1716753471] 'process raft request' (duration: 248.131657ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:13.084205Z","caller":"traceutil/trace.go:171","msg":"trace[981691689] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"245.779728ms","start":"2026-09-01T05:17:12.838396Z","end":"2026-09-01T05:17:13.084175Z","steps":["trace[981691689] 'process raft request' (duration: 245.52198ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:36.146368Z","caller":"traceutil/trace.go:171","msg":"trace[1965681611] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"163.735857ms","start":"2026-09-01T05:17:35.982586Z","end":"2026-09-01T05:17:36.146322Z","steps":["trace[1965681611] 'process raft request' (duration: 163.575782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:02.526945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.07501ms","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-09-01T05:18:02.527082Z","caller":"traceutil/trace.go:171","msg":"trace[1428751102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"148.294467ms","start":"2026-09-01T05:18:02.378756Z","end":"2026-09-01T05:18:02.52705Z","steps":["trace[1428751102] 'range keys from in-memory index tree' (duration: 147.777361ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:14.760933Z","caller":"traceutil/trace.go:171","msg":"trace[1724318901] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"106.159691ms","start":"2026-09-01T05:18:14.654725Z","end":"2026-09-01T05:18:14.760885Z","steps":["trace[1724318901] 'process raft request' (duration: 105.912764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:14.981865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.841187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:18:14.982016Z","caller":"traceutil/trace.go:171","msg":"trace[843379992] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"138.73199ms","start":"2026-09-01T05:18:14.843239Z","end":"2026-09-01T05:18:14.981971Z","steps":["trace[843379992] 'read index received' (duration: 16.215923ms)","trace[843379992] 'applied index is now lower than readState.Index' (duration: 122.514967ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:18:14.982033Z","caller":"traceutil/trace.go:171","msg":"trace[926350851] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"201.287033ms","start":"2026-09-01T05:18:14.780712Z","end":"2026-09-01T05:18:14.981999Z","steps":["trace[926350851] 'process raft request' (duration: 78.721864ms)","trace[926350851] 'compare' (duration: 121.659272ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:18:14.982186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.963897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-09-01T05:18:14.982234Z","caller":"traceutil/trace.go:171","msg":"trace[1251485636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1962; }","duration":"139.063731ms","start":"2026-09-01T05:18:14.843161Z","end":"2026-09-01T05:18:14.982224Z","steps":["trace[1251485636] 'agreement among raft nodes before linearized reading' (duration: 138.882655ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:18.751897Z","caller":"traceutil/trace.go:171","msg":"trace[99692103] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2068; }","duration":"149.619065ms","start":"2026-09-01T05:18:18.602253Z","end":"2026-09-01T05:18:18.751872Z","steps":["trace[99692103] 'read index received' (duration: 149.578214ms)","trace[99692103] 'applied index is now lower than readState.Index' (duration: 39.571µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:18:18.75213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.859273ms","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-09-01T05:18:18.752172Z","caller":"traceutil/trace.go:171","msg":"trace[1694428081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1990; }","duration":"149.955746ms","start":"2026-09-01T05:18:18.602205Z","end":"2026-09-01T05:18:18.75216Z","steps":["trace[1694428081] 'agreement among raft nodes before linearized reading' (duration: 149.774921ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:18.752293Z","caller":"traceutil/trace.go:171","msg":"trace[755341624] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"158.901283ms","start":"2026-09-01T05:18:18.59338Z","end":"2026-09-01T05:18:18.752281Z","steps":["trace[755341624] 'process raft request' (duration: 158.337506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:57.268684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.915197ms","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-09-01T05:18:57.268794Z","caller":"traceutil/trace.go:171","msg":"trace[1666950829] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2203; }","duration":"163.069812ms","start":"2026-09-01T05:18:57.105699Z","end":"2026-09-01T05:18:57.268769Z","steps":["trace[1666950829] 'range keys from in-memory index tree' (duration: 162.704231ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:57.268984Z","caller":"traceutil/trace.go:171","msg":"trace[900920872] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"132.428723ms","start":"2026-09-01T05:18:57.136518Z","end":"2026-09-01T05:18:57.268947Z","steps":["trace[900920872] 'process raft request' (duration: 100.117746ms)","trace[900920872] 'compare' (duration: 31.987788ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:18:57.75Z","caller":"traceutil/trace.go:171","msg":"trace[1968534431] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"218.536566ms","start":"2026-09-01T05:18:57.53144Z","end":"2026-09-01T05:18:57.749977Z","steps":["trace[1968534431] 'process raft request' (duration: 218.307299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:57.956846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.845903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:18:57.95721Z","caller":"traceutil/trace.go:171","msg":"trace[1308749834] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2293; }","duration":"171.673035ms","start":"2026-09-01T05:18:57.785521Z","end":"2026-09-01T05:18:57.957194Z","steps":["trace[1308749834] 'read index received' (duration: 33.41513ms)","trace[1308749834] 'applied index is now lower than readState.Index' (duration: 138.256595ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:18:57.957353Z","caller":"traceutil/trace.go:171","msg":"trace[1576814840] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"202.792407ms","start":"2026-09-01T05:18:57.754478Z","end":"2026-09-01T05:18:57.95727Z","steps":["trace[1576814840] 'process raft request' (duration: 202.466548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:57.957403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.887562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T05:18:57.957437Z","caller":"traceutil/trace.go:171","msg":"trace[138022676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2208; }","duration":"171.924643ms","start":"2026-09-01T05:18:57.785502Z","end":"2026-09-01T05:18:57.957427Z","steps":["trace[138022676] 'agreement among raft nodes before linearized reading' (duration: 171.773018ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:57.957419Z","caller":"traceutil/trace.go:171","msg":"trace[104532484] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"203.372745ms","start":"2026-09-01T05:18:57.754012Z","end":"2026-09-01T05:18:57.957385Z","steps":["trace[104532484] 'process raft request' (duration: 64.912593ms)","trace[104532484] 'compare' (duration: 137.71827ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:18:57.957571Z","caller":"traceutil/trace.go:171","msg":"trace[1340893748] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"176.806588ms","start":"2026-09-01T05:18:57.780753Z","end":"2026-09-01T05:18:57.957559Z","steps":["trace[1340893748] 'process raft request' (duration: 176.365225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:58.223392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.985782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:18:58.223494Z","caller":"traceutil/trace.go:171","msg":"trace[564691697] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"236.297242ms","start":"2026-09-01T05:18:57.98718Z","end":"2026-09-01T05:18:58.223478Z","steps":["trace[564691697] 'read index received' (duration: 133.046732ms)","trace[564691697] 'applied index is now lower than readState.Index' (duration: 103.24926ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:18:58.223556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.372484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:18:58.223588Z","caller":"traceutil/trace.go:171","msg":"trace[1117844670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"236.430686ms","start":"2026-09-01T05:18:57.987148Z","end":"2026-09-01T05:18:58.223578Z","steps":["trace[1117844670] 'agreement among raft nodes before linearized reading' (duration: 236.373514ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:58.22363Z","caller":"traceutil/trace.go:171","msg":"trace[1239753417] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"261.686473ms","start":"2026-09-01T05:18:57.961846Z","end":"2026-09-01T05:18:58.223533Z","steps":["trace[1239753417] 'process raft request' (duration: 158.344661ms)","trace[1239753417] 'compare' (duration: 102.819347ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:20:06.841922Z","caller":"traceutil/trace.go:171","msg":"trace[1864185209] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"177.140184ms","start":"2026-09-01T05:20:06.664743Z","end":"2026-09-01T05:20:06.841883Z","steps":["trace[1864185209] 'process raft request' (duration: 168.164017ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:06.842486Z","caller":"traceutil/trace.go:171","msg":"trace[177003842] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"158.218392ms","start":"2026-09-01T05:20:06.683882Z","end":"2026-09-01T05:20:06.842101Z","steps":["trace[177003842] 'process raft request' (duration: 157.930964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:20:12.482174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.161131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:20:12.482638Z","caller":"traceutil/trace.go:171","msg":"trace[1015412392] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"184.73981ms","start":"2026-09-01T05:20:12.29787Z","end":"2026-09-01T05:20:12.48261Z","steps":["trace[1015412392] 'process raft request' (duration: 56.049923ms)","trace[1015412392] 'compare' (duration: 128.039847ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:23:30.148999Z","caller":"traceutil/trace.go:171","msg":"trace[187935341] transaction","detail":"{read_only:false; response_revision:4042; number_of_response:1; }","duration":"146.973692ms","start":"2026-09-01T05:23:30.001996Z","end":"2026-09-01T05:23:30.148969Z","steps":["trace[187935341] 'process raft request' (duration: 146.812967ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:00.538764Z","caller":"traceutil/trace.go:171","msg":"trace[1266441922] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"119.478988ms","start":"2026-09-01T05:25:00.419257Z","end":"2026-09-01T05:25:00.538736Z","steps":["trace[1266441922] 'process raft request' (duration: 119.22724ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:00.53904Z","caller":"traceutil/trace.go:171","msg":"trace[1400991438] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"118.975623ms","start":"2026-09-01T05:25:00.420016Z","end":"2026-09-01T05:25:00.538992Z","steps":["trace[1400991438] 'process raft request' (duration: 118.663094ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:00.539094Z","caller":"traceutil/trace.go:171","msg":"trace[758224080] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5174; }","duration":"114.987445ms","start":"2026-09-01T05:25:00.424077Z","end":"2026-09-01T05:25:00.539064Z","steps":["trace[758224080] 'read index received' (duration: 114.502981ms)","trace[758224080] 'applied index is now lower than readState.Index' (duration: 483.734µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:25:00.539214Z","caller":"traceutil/trace.go:171","msg":"trace[1586452470] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"118.799278ms","start":"2026-09-01T05:25:00.420319Z","end":"2026-09-01T05:25:00.539118Z","steps":["trace[1586452470] 'process raft request' (duration: 118.641823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:00.53937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.276944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T05:25:00.539405Z","caller":"traceutil/trace.go:171","msg":"trace[2102352540] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4980; }","duration":"115.371637ms","start":"2026-09-01T05:25:00.424022Z","end":"2026-09-01T05:25:00.539394Z","steps":["trace[2102352540] 'agreement among raft nodes before linearized reading' (duration: 115.14829ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:00.542269Z","caller":"traceutil/trace.go:171","msg":"trace[819836989] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"109.935726ms","start":"2026-09-01T05:25:00.432291Z","end":"2026-09-01T05:25:00.542227Z","steps":["trace[819836989] 'process raft request' (duration: 109.7156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:06.772281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.541409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:25:06.772561Z","caller":"traceutil/trace.go:171","msg":"trace[176596706] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5355; }","duration":"103.578769ms","start":"2026-09-01T05:25:06.668954Z","end":"2026-09-01T05:25:06.772532Z","steps":["trace[176596706] 'read index received' (duration: 73.084108ms)","trace[176596706] 'applied index is now lower than readState.Index' (duration: 30.493351ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:25:06.772702Z","caller":"traceutil/trace.go:171","msg":"trace[1432666861] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"114.956634ms","start":"2026-09-01T05:25:06.65773Z","end":"2026-09-01T05:25:06.772687Z","steps":["trace[1432666861] 'process raft request' (duration: 114.706027ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:06.77271Z","caller":"traceutil/trace.go:171","msg":"trace[1969202177] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"178.030637ms","start":"2026-09-01T05:25:06.594642Z","end":"2026-09-01T05:25:06.772673Z","steps":["trace[1969202177] 'process raft request' (duration: 56.954982ms)","trace[1969202177] 'compare' (duration: 120.370944ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:25:06.773042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.059252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:25:06.773085Z","caller":"traceutil/trace.go:171","msg":"trace[1634581651] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5154; }","duration":"104.186537ms","start":"2026-09-01T05:25:06.668888Z","end":"2026-09-01T05:25:06.773074Z","steps":["trace[1634581651] 'agreement among raft nodes before linearized reading' (duration: 104.050103ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:09.878481Z","caller":"traceutil/trace.go:171","msg":"trace[2019146431] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"203.517069ms","start":"2026-09-01T05:25:09.674913Z","end":"2026-09-01T05:25:09.87843Z","steps":["trace[2019146431] 'process raft request' (duration: 203.318803ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:26.68497Z","caller":"traceutil/trace.go:171","msg":"trace[1403801515] linearizableReadLoop","detail":"{readStateIndex:5779; appliedIndex:5778; }","duration":"301.296446ms","start":"2026-09-01T05:25:26.383637Z","end":"2026-09-01T05:25:26.684933Z","steps":["trace[1403801515] 'read index received' (duration: 301.09403ms)","trace[1403801515] 'applied index is now lower than readState.Index' (duration: 200.746µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:25:26.685063Z","caller":"traceutil/trace.go:171","msg":"trace[1041754535] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"389.357366ms","start":"2026-09-01T05:25:26.295676Z","end":"2026-09-01T05:25:26.685033Z","steps":["trace[1041754535] 'process raft request' (duration: 389.047457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:26.685229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.293044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T05:25:26.68525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:25:26.295654Z","time spent":"389.46866ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:25:26.685279Z","caller":"traceutil/trace.go:171","msg":"trace[173043510] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5564; }","duration":"156.416858ms","start":"2026-09-01T05:25:26.528846Z","end":"2026-09-01T05:25:26.685263Z","steps":["trace[173043510] 'agreement among raft nodes before linearized reading' (duration: 156.287244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:26.685362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.672587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:25:26.685479Z","caller":"traceutil/trace.go:171","msg":"trace[1773978307] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5564; }","duration":"301.850173ms","start":"2026-09-01T05:25:26.383606Z","end":"2026-09-01T05:25:26.685456Z","steps":["trace[1773978307] 'agreement among raft nodes before linearized reading' (duration: 301.517443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:26.685542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:25:26.383588Z","time spent":"301.939725ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":100,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-09-01T05:25:36.438192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.63155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59ecd456-31e3-4d86-9e9a-606c711e6c01\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-01T05:25:36.438317Z","caller":"traceutil/trace.go:171","msg":"trace[1191107712] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-59ecd456-31e3-4d86-9e9a-606c711e6c01; range_end:; response_count:1; response_revision:5770; }","duration":"102.844377ms","start":"2026-09-01T05:25:36.335446Z","end":"2026-09-01T05:25:36.43829Z","steps":["trace[1191107712] 'range keys from in-memory index tree' (duration: 102.381673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:36.438318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.706189ms","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-09-01T05:25:36.438414Z","caller":"traceutil/trace.go:171","msg":"trace[1307002458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5770; }","duration":"108.877085ms","start":"2026-09-01T05:25:36.329513Z","end":"2026-09-01T05:25:36.43839Z","steps":["trace[1307002458] 'range keys from in-memory index tree' (duration: 108.3851ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:37.184227Z","caller":"traceutil/trace.go:171","msg":"trace[1891308599] linearizableReadLoop","detail":"{readStateIndex:5996; appliedIndex:5995; }","duration":"198.82117ms","start":"2026-09-01T05:25:36.985377Z","end":"2026-09-01T05:25:37.184198Z","steps":["trace[1891308599] 'read index received' (duration: 128.394321ms)","trace[1891308599] 'applied index is now lower than readState.Index' (duration: 70.425779ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:25:37.184517Z","caller":"traceutil/trace.go:171","msg":"trace[1153309285] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"249.932729ms","start":"2026-09-01T05:25:36.934568Z","end":"2026-09-01T05:25:37.184501Z","steps":["trace[1153309285] 'process raft request' (duration: 179.071207ms)","trace[1153309285] 'compare' (duration: 70.410239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:25:37.184784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.788335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T05:25:37.184845Z","caller":"traceutil/trace.go:171","msg":"trace[1611136233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5776; }","duration":"229.905949ms","start":"2026-09-01T05:25:36.954925Z","end":"2026-09-01T05:25:37.184831Z","steps":["trace[1611136233] 'agreement among raft nodes before linearized reading' (duration: 229.700253ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:37.184892Z","caller":"traceutil/trace.go:171","msg":"trace[588296171] transaction","detail":"{read_only:false; response_revision:5777; number_of_response:1; }","duration":"187.055143ms","start":"2026-09-01T05:25:36.997809Z","end":"2026-09-01T05:25:37.184865Z","steps":["trace[588296171] 'process raft request' (duration: 186.809666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:37.185151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.014958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-01T05:25:37.18523Z","caller":"traceutil/trace.go:171","msg":"trace[566077608] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5778; }","duration":"196.120881ms","start":"2026-09-01T05:25:36.989086Z","end":"2026-09-01T05:25:37.185207Z","steps":["trace[566077608] 'agreement among raft nodes before linearized reading' (duration: 195.947036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:25:37.18554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.258847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:25:37.185648Z","caller":"traceutil/trace.go:171","msg":"trace[607411572] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5778; }","duration":"171.419702ms","start":"2026-09-01T05:25:37.014207Z","end":"2026-09-01T05:25:37.185626Z","steps":["trace[607411572] 'agreement among raft nodes before linearized reading' (duration: 171.176954ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:40.022872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-09-01T05:25:40.106777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"81.559989ms","hash":2638135269,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T05:25:40.106866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2638135269,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-09-01T05:25:49.80004Z","caller":"traceutil/trace.go:171","msg":"trace[19346800] transaction","detail":"{read_only:false; response_revision:5902; number_of_response:1; }","duration":"116.772228ms","start":"2026-09-01T05:25:49.683229Z","end":"2026-09-01T05:25:49.800001Z","steps":["trace[19346800] 'process raft request' (duration: 100.813507ms)","trace[19346800] 'compare' (duration: 15.806257ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:25:49.829693Z","caller":"traceutil/trace.go:171","msg":"trace[290740634] transaction","detail":"{read_only:false; response_revision:5903; number_of_response:1; }","duration":"145.016372ms","start":"2026-09-01T05:25:49.684644Z","end":"2026-09-01T05:25:49.82966Z","steps":["trace[290740634] 'process raft request' (duration: 144.714993ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:25:49.829745Z","caller":"traceutil/trace.go:171","msg":"trace[2020331433] transaction","detail":"{read_only:false; response_revision:5904; number_of_response:1; }","duration":"144.388463ms","start":"2026-09-01T05:25:49.685324Z","end":"2026-09-01T05:25:49.829713Z","steps":["trace[2020331433] 'process raft request' (duration: 144.197018ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:31.94859Z","caller":"traceutil/trace.go:171","msg":"trace[289338977] transaction","detail":"{read_only:false; response_revision:6264; number_of_response:1; }","duration":"118.967345ms","start":"2026-09-01T05:26:31.829593Z","end":"2026-09-01T05:26:31.94856Z","steps":["trace[289338977] 'process raft request' (duration: 118.823881ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:30:40.029736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5822} {"level":"info","ts":"2026-09-01T05:30:40.114361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5822,"took":"82.446287ms","hash":1515134945,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":26296320,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T05:30:40.114447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1515134945,"revision":5822,"compact-revision":2959} {"level":"info","ts":"2026-09-01T05:31:58.949365Z","caller":"traceutil/trace.go:171","msg":"trace[903383689] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"360.922288ms","start":"2026-09-01T05:31:58.588397Z","end":"2026-09-01T05:31:58.94932Z","steps":["trace[903383689] 'process raft request' (duration: 360.539666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:31:58.949606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:31:58.588358Z","time spent":"361.129834ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:31:59.131965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.657342ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-01T05:31:59.1326Z","caller":"traceutil/trace.go:171","msg":"trace[533927554] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"242.94578ms","start":"2026-09-01T05:31:58.889616Z","end":"2026-09-01T05:31:59.132562Z","steps":["trace[533927554] 'process raft request' (duration: 242.507817ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:31:59.132411Z","caller":"traceutil/trace.go:171","msg":"trace[859082050] linearizableReadLoop","detail":"{readStateIndex:8963; appliedIndex:8961; }","duration":"340.684519ms","start":"2026-09-01T05:31:58.791699Z","end":"2026-09-01T05:31:59.132383Z","steps":["trace[859082050] 'read index received' (duration: 157.384972ms)","trace[859082050] 'applied index is now lower than readState.Index' (duration: 183.297387ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:31:59.132968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.238545ms","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-09-01T05:31:59.133069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.362725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:31:59.133099Z","caller":"traceutil/trace.go:171","msg":"trace[633194844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8651; }","duration":"341.432211ms","start":"2026-09-01T05:31:58.791635Z","end":"2026-09-01T05:31:59.133067Z","steps":["trace[633194844] 'agreement among raft nodes before linearized reading' (duration: 341.06086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:31:59.133225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:31:58.79161Z","time spent":"341.587495ms","remote":"127.0.0.1:39398","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":"info","ts":"2026-09-01T05:31:59.133121Z","caller":"traceutil/trace.go:171","msg":"trace[484707384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8651; }","duration":"148.467388ms","start":"2026-09-01T05:31:58.984636Z","end":"2026-09-01T05:31:59.133103Z","steps":["trace[484707384] 'agreement among raft nodes before linearized reading' (duration: 148.360705ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:01.789328Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002315.wal"} {"level":"info","ts":"2026-09-01T05:32:10.050339Z","caller":"traceutil/trace.go:171","msg":"trace[104695880] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"219.500947ms","start":"2026-09-01T05:32:09.830779Z","end":"2026-09-01T05:32:10.05028Z","steps":["trace[104695880] 'process raft request' (duration: 159.873475ms)","trace[104695880] 'compare' (duration: 59.373855ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:10.319296Z","caller":"traceutil/trace.go:171","msg":"trace[654824181] linearizableReadLoop","detail":"{readStateIndex:9026; appliedIndex:9025; }","duration":"263.608571ms","start":"2026-09-01T05:32:10.055651Z","end":"2026-09-01T05:32:10.31926Z","steps":["trace[654824181] 'read index received' (duration: 263.247931ms)","trace[654824181] 'applied index is now lower than readState.Index' (duration: 359.14µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:10.319583Z","caller":"traceutil/trace.go:171","msg":"trace[525541408] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"264.715264ms","start":"2026-09-01T05:32:10.054837Z","end":"2026-09-01T05:32:10.319553Z","steps":["trace[525541408] 'process raft request' (duration: 264.182748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:10.319824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.04101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:32:10.319977Z","caller":"traceutil/trace.go:171","msg":"trace[1079876434] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:8711; }","duration":"183.284307ms","start":"2026-09-01T05:32:10.136656Z","end":"2026-09-01T05:32:10.319941Z","steps":["trace[1079876434] 'agreement among raft nodes before linearized reading' (duration: 182.987938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:10.320037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.362693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222798"} {"level":"info","ts":"2026-09-01T05:32:10.320169Z","caller":"traceutil/trace.go:171","msg":"trace[679595097] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8711; }","duration":"264.549159ms","start":"2026-09-01T05:32:10.055578Z","end":"2026-09-01T05:32:10.320127Z","steps":["trace[679595097] 'agreement among raft nodes before linearized reading' (duration: 263.91939ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:11.92083Z","caller":"traceutil/trace.go:171","msg":"trace[575811057] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"151.206739ms","start":"2026-09-01T05:32:11.769586Z","end":"2026-09-01T05:32:11.920792Z","steps":["trace[575811057] 'process raft request' (duration: 129.528658ms)","trace[575811057] 'compare' (duration: 21.503455ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:32:11.920848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.26317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T05:32:11.920963Z","caller":"traceutil/trace.go:171","msg":"trace[1939107289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8718; }","duration":"152.449976ms","start":"2026-09-01T05:32:11.768483Z","end":"2026-09-01T05:32:11.920933Z","steps":["trace[1939107289] 'range keys from in-memory index tree' (duration: 151.964901ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:37.629847Z","caller":"traceutil/trace.go:171","msg":"trace[874196310] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"121.302035ms","start":"2026-09-01T05:32:37.508516Z","end":"2026-09-01T05:32:37.629818Z","steps":["trace[874196310] 'process raft request' (duration: 120.520201ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:41.572899Z","caller":"traceutil/trace.go:171","msg":"trace[1681970447] linearizableReadLoop","detail":"{readStateIndex:9250; appliedIndex:9249; }","duration":"432.364508ms","start":"2026-09-01T05:32:41.1405Z","end":"2026-09-01T05:32:41.572864Z","steps":["trace[1681970447] 'read index received' (duration: 432.178752ms)","trace[1681970447] 'applied index is now lower than readState.Index' (duration: 183.785µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:41.57301Z","caller":"traceutil/trace.go:171","msg":"trace[149239763] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"446.959978ms","start":"2026-09-01T05:32:41.126Z","end":"2026-09-01T05:32:41.57296Z","steps":["trace[149239763] 'process raft request' (duration: 446.67438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:41.5732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.623305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T05:32:41.573276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:32:41.125964Z","time spent":"447.156334ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:32:41.573326Z","caller":"traceutil/trace.go:171","msg":"trace[1937257501] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8926; }","duration":"432.851631ms","start":"2026-09-01T05:32:41.140448Z","end":"2026-09-01T05:32:41.573299Z","steps":["trace[1937257501] 'agreement among raft nodes before linearized reading' (duration: 432.543572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:41.573387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:32:41.140423Z","time spent":"432.952005ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-09-01T05:32:41.689429Z","caller":"traceutil/trace.go:171","msg":"trace[1595759398] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"435.096708ms","start":"2026-09-01T05:32:41.254306Z","end":"2026-09-01T05:32:41.689403Z","steps":["trace[1595759398] 'process raft request' (duration: 406.155503ms)","trace[1595759398] 'compare' (duration: 28.7513ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:41.689746Z","caller":"traceutil/trace.go:171","msg":"trace[1116422299] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"365.562513ms","start":"2026-09-01T05:32:41.324159Z","end":"2026-09-01T05:32:41.689721Z","steps":["trace[1116422299] 'process raft request' (duration: 365.184712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:41.689981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:32:41.324102Z","time spent":"365.762409ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:32:41.690022Z","caller":"traceutil/trace.go:171","msg":"trace[1945940910] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"226.112682ms","start":"2026-09-01T05:32:41.463886Z","end":"2026-09-01T05:32:41.689999Z","steps":["trace[1945940910] 'process raft request' (duration: 225.732121ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:41.690032Z","caller":"traceutil/trace.go:171","msg":"trace[2050090071] linearizableReadLoop","detail":"{readStateIndex:9253; appliedIndex:9250; }","duration":"116.933515ms","start":"2026-09-01T05:32:41.573035Z","end":"2026-09-01T05:32:41.689969Z","steps":["trace[2050090071] 'read index received' (duration: 87.438414ms)","trace[2050090071] 'applied index is now lower than readState.Index' (duration: 29.493171ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:32:41.690306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.85153ms","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-09-01T05:32:41.69036Z","caller":"traceutil/trace.go:171","msg":"trace[1757214897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8929; }","duration":"214.950552ms","start":"2026-09-01T05:32:41.475393Z","end":"2026-09-01T05:32:41.690344Z","steps":["trace[1757214897] 'agreement among raft nodes before linearized reading' (duration: 214.771097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:32:41.690398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:32:41.25428Z","time spent":"435.365126ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:32:43.934049Z","caller":"traceutil/trace.go:171","msg":"trace[1817143509] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"110.97058ms","start":"2026-09-01T05:32:43.823012Z","end":"2026-09-01T05:32:43.933983Z","steps":["trace[1817143509] 'process raft request' (duration: 16.22657ms)","trace[1817143509] 'compare' (duration: 94.560605ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:32:44.404304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.967332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:32:44.404431Z","caller":"traceutil/trace.go:171","msg":"trace[1897141487] transaction","detail":"{read_only:false; response_revision:8945; number_of_response:1; }","duration":"197.866647ms","start":"2026-09-01T05:32:44.206542Z","end":"2026-09-01T05:32:44.404408Z","steps":["trace[1897141487] 'process raft request' (duration: 93.695489ms)","trace[1897141487] 'compare' (duration: 103.698294ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:52.288737Z","caller":"traceutil/trace.go:171","msg":"trace[960068428] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"139.561405ms","start":"2026-09-01T05:32:52.149152Z","end":"2026-09-01T05:32:52.288714Z","steps":["trace[960068428] 'process raft request' (duration: 139.40203ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:52.589501Z","caller":"traceutil/trace.go:171","msg":"trace[1044163805] transaction","detail":"{read_only:false; response_revision:8987; number_of_response:1; }","duration":"152.029442ms","start":"2026-09-01T05:32:52.437428Z","end":"2026-09-01T05:32:52.589457Z","steps":["trace[1044163805] 'process raft request' (duration: 116.895274ms)","trace[1044163805] 'compare' (duration: 34.554801ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:52.625577Z","caller":"traceutil/trace.go:171","msg":"trace[486395476] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"162.700698ms","start":"2026-09-01T05:32:52.462827Z","end":"2026-09-01T05:32:52.625528Z","steps":["trace[486395476] 'process raft request' (duration: 162.479802ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:55.706414Z","caller":"traceutil/trace.go:171","msg":"trace[1914248627] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"132.100174ms","start":"2026-09-01T05:32:55.574284Z","end":"2026-09-01T05:32:55.706384Z","steps":["trace[1914248627] 'process raft request' (duration: 131.944979ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:55.952096Z","caller":"traceutil/trace.go:171","msg":"trace[1500396469] transaction","detail":"{read_only:false; response_revision:9016; number_of_response:1; }","duration":"128.599981ms","start":"2026-09-01T05:32:55.82346Z","end":"2026-09-01T05:32:55.95206Z","steps":["trace[1500396469] 'process raft request' (duration: 128.397495ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:55.952281Z","caller":"traceutil/trace.go:171","msg":"trace[994039430] transaction","detail":"{read_only:false; response_revision:9017; number_of_response:1; }","duration":"125.127318ms","start":"2026-09-01T05:32:55.827115Z","end":"2026-09-01T05:32:55.952242Z","steps":["trace[994039430] 'process raft request' (duration: 124.898902ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:56.09922Z","caller":"traceutil/trace.go:171","msg":"trace[159987722] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"141.373098ms","start":"2026-09-01T05:32:55.957822Z","end":"2026-09-01T05:32:56.099195Z","steps":["trace[159987722] 'process raft request' (duration: 92.339769ms)","trace[159987722] 'compare' (duration: 48.448902ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:32:56.099505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.261378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:32:56.099121Z","caller":"traceutil/trace.go:171","msg":"trace[1030458234] linearizableReadLoop","detail":"{readStateIndex:9345; appliedIndex:9344; }","duration":"110.861376ms","start":"2026-09-01T05:32:55.988223Z","end":"2026-09-01T05:32:56.099084Z","steps":["trace[1030458234] 'read index received' (duration: 61.845177ms)","trace[1030458234] 'applied index is now lower than readState.Index' (duration: 49.014729ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:56.099648Z","caller":"traceutil/trace.go:171","msg":"trace[1162585342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9018; }","duration":"111.453383ms","start":"2026-09-01T05:32:55.988162Z","end":"2026-09-01T05:32:56.099616Z","steps":["trace[1162585342] 'agreement among raft nodes before linearized reading' (duration: 111.226777ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:57.727391Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:33:57.737894Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T05:33:57.738057Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T05:34:26.489963Z","caller":"traceutil/trace.go:171","msg":"trace[1730954781] transaction","detail":"{read_only:false; response_revision:9867; number_of_response:1; }","duration":"164.907103ms","start":"2026-09-01T05:34:26.32503Z","end":"2026-09-01T05:34:26.489937Z","steps":["trace[1730954781] 'process raft request' (duration: 164.705617ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:35:40.0373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8003} {"level":"info","ts":"2026-09-01T05:35:40.114063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8003,"took":"73.876711ms","hash":2578932886,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":27963392,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T05:35:40.114153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2578932886,"revision":8003,"compact-revision":5822} {"level":"warn","ts":"2026-09-01T05:39:48.141426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.046938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:39:48.141706Z","caller":"traceutil/trace.go:171","msg":"trace[1637053855] transaction","detail":"{read_only:false; response_revision:12238; number_of_response:1; }","duration":"206.955033ms","start":"2026-09-01T05:39:47.934701Z","end":"2026-09-01T05:39:48.141656Z","steps":["trace[1637053855] 'process raft request' (duration: 76.592175ms)","trace[1637053855] 'compare' (duration: 129.857543ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:39:48.141772Z","caller":"traceutil/trace.go:171","msg":"trace[1389227538] transaction","detail":"{read_only:false; response_revision:12239; number_of_response:1; }","duration":"204.906702ms","start":"2026-09-01T05:39:47.936828Z","end":"2026-09-01T05:39:48.141734Z","steps":["trace[1389227538] 'process raft request' (duration: 204.713497ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:08.617407Z","caller":"traceutil/trace.go:171","msg":"trace[762054853] linearizableReadLoop","detail":"{readStateIndex:12798; appliedIndex:12797; }","duration":"117.478688ms","start":"2026-09-01T05:40:08.499893Z","end":"2026-09-01T05:40:08.617372Z","steps":["trace[762054853] 'read index received' (duration: 117.181619ms)","trace[762054853] 'applied index is now lower than readState.Index' (duration: 295.769µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:40:08.617529Z","caller":"traceutil/trace.go:171","msg":"trace[1740352450] transaction","detail":"{read_only:false; response_revision:12368; number_of_response:1; }","duration":"139.565928ms","start":"2026-09-01T05:40:08.477919Z","end":"2026-09-01T05:40:08.617485Z","steps":["trace[1740352450] 'process raft request' (duration: 139.269189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:08.617702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.783937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7695"} {"level":"info","ts":"2026-09-01T05:40:08.617772Z","caller":"traceutil/trace.go:171","msg":"trace[429541764] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:12368; }","duration":"117.915111ms","start":"2026-09-01T05:40:08.499843Z","end":"2026-09-01T05:40:08.617758Z","steps":["trace[429541764] 'agreement among raft nodes before linearized reading' (duration: 117.673994ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:08.940141Z","caller":"traceutil/trace.go:171","msg":"trace[1357988314] transaction","detail":"{read_only:false; response_revision:12371; number_of_response:1; }","duration":"122.884718ms","start":"2026-09-01T05:40:08.817196Z","end":"2026-09-01T05:40:08.940081Z","steps":["trace[1357988314] 'process raft request' (duration: 122.649491ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:08.940516Z","caller":"traceutil/trace.go:171","msg":"trace[126734523] transaction","detail":"{read_only:false; response_revision:12372; number_of_response:1; }","duration":"123.214398ms","start":"2026-09-01T05:40:08.81723Z","end":"2026-09-01T05:40:08.940445Z","steps":["trace[126734523] 'process raft request' (duration: 122.800156ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:09.427346Z","caller":"traceutil/trace.go:171","msg":"trace[1210791367] linearizableReadLoop","detail":"{readStateIndex:12804; appliedIndex:12803; }","duration":"183.941755ms","start":"2026-09-01T05:40:09.243352Z","end":"2026-09-01T05:40:09.427294Z","steps":["trace[1210791367] 'read index received' (duration: 183.533073ms)","trace[1210791367] 'applied index is now lower than readState.Index' (duration: 407.352µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:40:09.427422Z","caller":"traceutil/trace.go:171","msg":"trace[418712260] transaction","detail":"{read_only:false; response_revision:12373; number_of_response:1; }","duration":"267.394262ms","start":"2026-09-01T05:40:09.159999Z","end":"2026-09-01T05:40:09.427393Z","steps":["trace[418712260] 'process raft request' (duration: 267.015731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:09.427518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.143092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:40:09.427552Z","caller":"traceutil/trace.go:171","msg":"trace[2088521151] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12373; }","duration":"184.288475ms","start":"2026-09-01T05:40:09.243254Z","end":"2026-09-01T05:40:09.427542Z","steps":["trace[2088521151] 'agreement among raft nodes before linearized reading' (duration: 184.203363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:09.427785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.192634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:40:09.427818Z","caller":"traceutil/trace.go:171","msg":"trace[803253371] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12373; }","duration":"182.246175ms","start":"2026-09-01T05:40:09.245564Z","end":"2026-09-01T05:40:09.42781Z","steps":["trace[803253371] 'agreement among raft nodes before linearized reading' (duration: 182.195374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:09.427782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.291796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:40:09.427898Z","caller":"traceutil/trace.go:171","msg":"trace[288090984] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12373; }","duration":"184.478491ms","start":"2026-09-01T05:40:09.243376Z","end":"2026-09-01T05:40:09.427855Z","steps":["trace[288090984] 'agreement among raft nodes before linearized reading' (duration: 184.280206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:09.427989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.244715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-09-01T05:40:09.427984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.231615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5031"} {"level":"info","ts":"2026-09-01T05:40:09.42803Z","caller":"traceutil/trace.go:171","msg":"trace[830608374] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12373; }","duration":"183.307107ms","start":"2026-09-01T05:40:09.24471Z","end":"2026-09-01T05:40:09.428018Z","steps":["trace[830608374] 'agreement among raft nodes before linearized reading' (duration: 183.188134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:09.428032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.591696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:40:09.428077Z","caller":"traceutil/trace.go:171","msg":"trace[1010671158] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12373; }","duration":"182.304338ms","start":"2026-09-01T05:40:09.245721Z","end":"2026-09-01T05:40:09.428026Z","steps":["trace[1010671158] 'agreement among raft nodes before linearized reading' (duration: 182.080391ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:09.428138Z","caller":"traceutil/trace.go:171","msg":"trace[1669577589] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12373; }","duration":"182.697159ms","start":"2026-09-01T05:40:09.245384Z","end":"2026-09-01T05:40:09.428081Z","steps":["trace[1669577589] 'agreement among raft nodes before linearized reading' (duration: 182.519534ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:40:40.046424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10368} {"level":"info","ts":"2026-09-01T05:40:40.139641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10368,"took":"90.088102ms","hash":3971225158,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":25006080,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T05:40:40.139709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3971225158,"revision":10368,"compact-revision":8003} {"level":"info","ts":"2026-09-01T05:45:40.055853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12590} {"level":"info","ts":"2026-09-01T05:45:40.148245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12590,"took":"88.298274ms","hash":2140414583,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":28241920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T05:45:40.148326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140414583,"revision":12590,"compact-revision":10368} {"level":"info","ts":"2026-09-01T05:46:17.611613Z","caller":"traceutil/trace.go:171","msg":"trace[2038808368] transaction","detail":"{read_only:false; response_revision:14932; number_of_response:1; }","duration":"103.901035ms","start":"2026-09-01T05:46:17.50768Z","end":"2026-09-01T05:46:17.611581Z","steps":["trace[2038808368] 'process raft request' (duration: 103.719709ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:46:21.302983Z","caller":"traceutil/trace.go:171","msg":"trace[853055854] linearizableReadLoop","detail":"{readStateIndex:15469; appliedIndex:15468; }","duration":"172.698654ms","start":"2026-09-01T05:46:21.13025Z","end":"2026-09-01T05:46:21.302948Z","steps":["trace[853055854] 'read index received' (duration: 172.473877ms)","trace[853055854] 'applied index is now lower than readState.Index' (duration: 219.237µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:46:21.303135Z","caller":"traceutil/trace.go:171","msg":"trace[79170307] transaction","detail":"{read_only:false; response_revision:14950; number_of_response:1; }","duration":"177.64733ms","start":"2026-09-01T05:46:21.125433Z","end":"2026-09-01T05:46:21.30308Z","steps":["trace[79170307] 'process raft request' (duration: 177.291699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:46:21.303338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.027213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T05:46:21.303388Z","caller":"traceutil/trace.go:171","msg":"trace[1235096412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14950; }","duration":"173.150527ms","start":"2026-09-01T05:46:21.130224Z","end":"2026-09-01T05:46:21.303375Z","steps":["trace[1235096412] 'agreement among raft nodes before linearized reading' (duration: 172.923211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:46:21.303453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.08686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:46:21.303563Z","caller":"traceutil/trace.go:171","msg":"trace[1986121039] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:14950; }","duration":"105.261155ms","start":"2026-09-01T05:46:21.198275Z","end":"2026-09-01T05:46:21.303536Z","steps":["trace[1986121039] 'agreement among raft nodes before linearized reading' (duration: 104.998617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:46:53.376112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.19412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:46:53.376236Z","caller":"traceutil/trace.go:171","msg":"trace[1261370036] transaction","detail":"{read_only:false; response_revision:15114; number_of_response:1; }","duration":"181.530574ms","start":"2026-09-01T05:46:53.194685Z","end":"2026-09-01T05:46:53.376216Z","steps":["trace[1261370036] 'process raft request' (duration: 31.120328ms)","trace[1261370036] 'compare' (duration: 150.085537ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:47:01.649208Z","caller":"traceutil/trace.go:171","msg":"trace[1265619399] linearizableReadLoop","detail":"{readStateIndex:15699; appliedIndex:15698; }","duration":"122.454112ms","start":"2026-09-01T05:47:01.526721Z","end":"2026-09-01T05:47:01.649175Z","steps":["trace[1265619399] 'read index received' (duration: 122.06849ms)","trace[1265619399] 'applied index is now lower than readState.Index' (duration: 384.092µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:47:01.649323Z","caller":"traceutil/trace.go:171","msg":"trace[1055493387] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"203.399009ms","start":"2026-09-01T05:47:01.445864Z","end":"2026-09-01T05:47:01.649263Z","steps":["trace[1055493387] 'process raft request' (duration: 203.09056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:01.649522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.459364ms","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-09-01T05:47:01.649574Z","caller":"traceutil/trace.go:171","msg":"trace[151788949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15170; }","duration":"119.542486ms","start":"2026-09-01T05:47:01.530019Z","end":"2026-09-01T05:47:01.649561Z","steps":["trace[151788949] 'agreement among raft nodes before linearized reading' (duration: 119.362001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:01.64956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.804593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T05:47:01.64967Z","caller":"traceutil/trace.go:171","msg":"trace[1659907861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15170; }","duration":"122.991678ms","start":"2026-09-01T05:47:01.526655Z","end":"2026-09-01T05:47:01.649647Z","steps":["trace[1659907861] 'agreement among raft nodes before linearized reading' (duration: 122.648087ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:47:02.544221Z","caller":"traceutil/trace.go:171","msg":"trace[2039781078] transaction","detail":"{read_only:false; response_revision:15178; number_of_response:1; }","duration":"120.907106ms","start":"2026-09-01T05:47:02.423291Z","end":"2026-09-01T05:47:02.544198Z","steps":["trace[2039781078] 'process raft request' (duration: 120.245536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:13.204488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.107719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-01T05:47:13.204581Z","caller":"traceutil/trace.go:171","msg":"trace[29307583] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15350; }","duration":"108.221592ms","start":"2026-09-01T05:47:13.09634Z","end":"2026-09-01T05:47:13.204561Z","steps":["trace[29307583] 'range keys from in-memory index tree' (duration: 107.877572ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:47:13.545546Z","caller":"traceutil/trace.go:171","msg":"trace[270678277] linearizableReadLoop","detail":"{readStateIndex:15883; appliedIndex:15882; }","duration":"239.994248ms","start":"2026-09-01T05:47:13.305516Z","end":"2026-09-01T05:47:13.54551Z","steps":["trace[270678277] 'read index received' (duration: 239.640158ms)","trace[270678277] 'applied index is now lower than readState.Index' (duration: 352.68µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:47:13.545683Z","caller":"traceutil/trace.go:171","msg":"trace[1329111291] transaction","detail":"{read_only:false; response_revision:15351; number_of_response:1; }","duration":"286.985575ms","start":"2026-09-01T05:47:13.258653Z","end":"2026-09-01T05:47:13.545638Z","steps":["trace[1329111291] 'process raft request' (duration: 286.635605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:13.545876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.316049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T05:47:13.54593Z","caller":"traceutil/trace.go:171","msg":"trace[318259326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15351; }","duration":"240.475682ms","start":"2026-09-01T05:47:13.305442Z","end":"2026-09-01T05:47:13.545917Z","steps":["trace[318259326] 'agreement among raft nodes before linearized reading' (duration: 240.251836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:13.546015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.633606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.181\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-09-01T05:47:13.54611Z","caller":"traceutil/trace.go:171","msg":"trace[411798240] range","detail":"{range_begin:/registry/masterleases/162.253.55.181; range_end:; response_count:1; response_revision:15351; }","duration":"106.811211ms","start":"2026-09-01T05:47:13.439284Z","end":"2026-09-01T05:47:13.546096Z","steps":["trace[411798240] 'agreement among raft nodes before linearized reading' (duration: 106.592044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:47:13.546259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.150254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-lxdmb.18d11c4a29b0be28\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-01T05:47:13.54665Z","caller":"traceutil/trace.go:171","msg":"trace[11217167] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-lxdmb.18d11c4a29b0be28; range_end:; response_count:1; response_revision:15351; }","duration":"119.612218ms","start":"2026-09-01T05:47:13.427008Z","end":"2026-09-01T05:47:13.54662Z","steps":["trace[11217167] 'agreement among raft nodes before linearized reading' (duration: 118.854356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:46.414947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.127768ms","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-09-01T05:48:46.41505Z","caller":"traceutil/trace.go:171","msg":"trace[1786344144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16399; }","duration":"221.343384ms","start":"2026-09-01T05:48:46.193687Z","end":"2026-09-01T05:48:46.41503Z","steps":["trace[1786344144] 'range keys from in-memory index tree' (duration: 220.880771ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:48:46.416987Z","caller":"traceutil/trace.go:171","msg":"trace[1015797797] linearizableReadLoop","detail":"{readStateIndex:16964; appliedIndex:16963; }","duration":"111.545218ms","start":"2026-09-01T05:48:46.305411Z","end":"2026-09-01T05:48:46.416956Z","steps":["trace[1015797797] 'read index received' (duration: 111.26861ms)","trace[1015797797] 'applied index is now lower than readState.Index' (duration: 275.288µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:48:46.417115Z","caller":"traceutil/trace.go:171","msg":"trace[1557862419] transaction","detail":"{read_only:false; response_revision:16400; number_of_response:1; }","duration":"135.362867ms","start":"2026-09-01T05:48:46.28174Z","end":"2026-09-01T05:48:46.417102Z","steps":["trace[1557862419] 'process raft request' (duration: 135.041668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:46.417361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.709843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T05:48:46.417451Z","caller":"traceutil/trace.go:171","msg":"trace[267201337] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16400; }","duration":"110.808007ms","start":"2026-09-01T05:48:46.306617Z","end":"2026-09-01T05:48:46.417425Z","steps":["trace[267201337] 'agreement among raft nodes before linearized reading' (duration: 110.56805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:46.417822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.387133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:48:46.417868Z","caller":"traceutil/trace.go:171","msg":"trace[1944866556] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16400; }","duration":"112.515017ms","start":"2026-09-01T05:48:46.305339Z","end":"2026-09-01T05:48:46.417854Z","steps":["trace[1944866556] 'agreement among raft nodes before linearized reading' (duration: 112.430474ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:50:40.06572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14659} {"level":"info","ts":"2026-09-01T05:50:40.154694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14659,"took":"85.831623ms","hash":3378545224,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":32137216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T05:50:40.15478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3378545224,"revision":14659,"compact-revision":12590} {"level":"info","ts":"2026-09-01T05:54:30.605035Z","caller":"traceutil/trace.go:171","msg":"trace[1008880694] transaction","detail":"{read_only:false; response_revision:18863; number_of_response:1; }","duration":"161.842336ms","start":"2026-09-01T05:54:30.443149Z","end":"2026-09-01T05:54:30.604992Z","steps":["trace[1008880694] 'process raft request' (duration: 161.592909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:54:36.929312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.782883ms","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":"warn","ts":"2026-09-01T05:54:36.929351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.206834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-01T05:54:36.929467Z","caller":"traceutil/trace.go:171","msg":"trace[464398893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18948; }","duration":"146.381019ms","start":"2026-09-01T05:54:36.783052Z","end":"2026-09-01T05:54:36.929433Z","steps":["trace[464398893] 'range keys from in-memory index tree' (duration: 145.989877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:54:36.929354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.701161ms","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-09-01T05:54:36.929611Z","caller":"traceutil/trace.go:171","msg":"trace[983946572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18948; }","duration":"140.990989ms","start":"2026-09-01T05:54:36.78859Z","end":"2026-09-01T05:54:36.929581Z","steps":["trace[983946572] 'range keys from in-memory index tree' (duration: 140.499995ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:54:36.92946Z","caller":"traceutil/trace.go:171","msg":"trace[119667306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18948; }","duration":"141.956428ms","start":"2026-09-01T05:54:36.787446Z","end":"2026-09-01T05:54:36.929402Z","steps":["trace[119667306] 'range keys from in-memory index tree' (duration: 141.603788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:54:36.929869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.248103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-01T05:54:36.92991Z","caller":"traceutil/trace.go:171","msg":"trace[2103405239] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18948; }","duration":"148.329997ms","start":"2026-09-01T05:54:36.781569Z","end":"2026-09-01T05:54:36.929899Z","steps":["trace[2103405239] 'range keys from in-memory index tree' (duration: 148.095109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:54:36.930099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.371041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-01T05:54:36.930499Z","caller":"traceutil/trace.go:171","msg":"trace[1858216872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18948; }","duration":"140.777313ms","start":"2026-09-01T05:54:36.789694Z","end":"2026-09-01T05:54:36.930472Z","steps":["trace[1858216872] 'range keys from in-memory index tree' (duration: 140.209256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:54:36.930313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.044888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-01T05:54:36.930835Z","caller":"traceutil/trace.go:171","msg":"trace[37484797] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18948; }","duration":"146.568874ms","start":"2026-09-01T05:54:36.784249Z","end":"2026-09-01T05:54:36.930818Z","steps":["trace[37484797] 'range keys from in-memory index tree' (duration: 145.814782ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:54:36.930375Z","caller":"traceutil/trace.go:171","msg":"trace[1187689078] transaction","detail":"{read_only:false; response_revision:18949; number_of_response:1; }","duration":"100.538333ms","start":"2026-09-01T05:54:36.829823Z","end":"2026-09-01T05:54:36.930361Z","steps":["trace[1187689078] 'process raft request' (duration: 93.589348ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:55:13.978781Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:55:13.986741Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T05:55:13.986993Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T05:55:40.07397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17115} {"level":"info","ts":"2026-09-01T05:55:40.202515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17115,"took":"124.000547ms","hash":256447868,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":33939456,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T05:55:40.202617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":256447868,"revision":17115,"compact-revision":14659} {"level":"info","ts":"2026-09-01T06:00:40.082198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19681} {"level":"info","ts":"2026-09-01T06:00:40.208353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19681,"took":"122.518824ms","hash":3860903447,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T06:00:40.208482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3860903447,"revision":19681,"compact-revision":17115} {"level":"info","ts":"2026-09-01T06:01:01.129341Z","caller":"traceutil/trace.go:171","msg":"trace[418314597] transaction","detail":"{read_only:false; response_revision:21922; number_of_response:1; }","duration":"114.152267ms","start":"2026-09-01T06:01:01.015154Z","end":"2026-09-01T06:01:01.129306Z","steps":["trace[418314597] 'process raft request' (duration: 113.881949ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:01.470439Z","caller":"traceutil/trace.go:171","msg":"trace[2054147637] transaction","detail":"{read_only:false; response_revision:21923; number_of_response:1; }","duration":"148.511111ms","start":"2026-09-01T06:01:01.321903Z","end":"2026-09-01T06:01:01.470414Z","steps":["trace[2054147637] 'process raft request' (duration: 148.377527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:01.919809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.144296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T06:01:01.920074Z","caller":"traceutil/trace.go:171","msg":"trace[1502698930] transaction","detail":"{read_only:false; response_revision:21924; number_of_response:1; }","duration":"598.149744ms","start":"2026-09-01T06:01:01.321894Z","end":"2026-09-01T06:01:01.920044Z","steps":["trace[1502698930] 'process raft request' (duration: 321.668598ms)","trace[1502698930] 'compare' (duration: 275.718393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:01.920172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.32187Z","time spent":"598.258317ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:01.920447Z","caller":"traceutil/trace.go:171","msg":"trace[487325044] transaction","detail":"{read_only:false; response_revision:21925; number_of_response:1; }","duration":"597.388331ms","start":"2026-09-01T06:01:01.323044Z","end":"2026-09-01T06:01:01.920432Z","steps":["trace[487325044] 'process raft request' (duration: 596.869006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:01.920524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.323028Z","time spent":"597.458984ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:01.920821Z","caller":"traceutil/trace.go:171","msg":"trace[36646594] transaction","detail":"{read_only:false; response_revision:21926; number_of_response:1; }","duration":"534.081444ms","start":"2026-09-01T06:01:01.386692Z","end":"2026-09-01T06:01:01.920773Z","steps":["trace[36646594] 'process raft request' (duration: 533.644811ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:01.921006Z","caller":"traceutil/trace.go:171","msg":"trace[1314552274] transaction","detail":"{read_only:false; response_revision:21927; number_of_response:1; }","duration":"491.944311ms","start":"2026-09-01T06:01:01.429013Z","end":"2026-09-01T06:01:01.920958Z","steps":["trace[1314552274] 'process raft request' (duration: 491.720744ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:01.92103Z","caller":"traceutil/trace.go:171","msg":"trace[225715771] transaction","detail":"{read_only:false; response_revision:21928; number_of_response:1; }","duration":"424.881673ms","start":"2026-09-01T06:01:01.496107Z","end":"2026-09-01T06:01:01.920988Z","steps":["trace[225715771] 'process raft request' (duration: 424.80438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:01.92118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.428989Z","time spent":"492.116606ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:01.921252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.496069Z","time spent":"425.053428ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:01.921186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.386669Z","time spent":"534.331531ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:01.921004Z","caller":"traceutil/trace.go:171","msg":"trace[347149972] linearizableReadLoop","detail":"{readStateIndex:22673; appliedIndex:22670; }","duration":"596.174336ms","start":"2026-09-01T06:01:01.324793Z","end":"2026-09-01T06:01:01.920967Z","steps":["trace[347149972] 'read index received' (duration: 145.506442ms)","trace[347149972] 'applied index is now lower than readState.Index' (duration: 450.665284ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:01.921268Z","caller":"traceutil/trace.go:171","msg":"trace[1981944456] transaction","detail":"{read_only:false; response_revision:21929; number_of_response:1; }","duration":"147.961094ms","start":"2026-09-01T06:01:01.773288Z","end":"2026-09-01T06:01:01.921249Z","steps":["trace[1981944456] 'process raft request' (duration: 147.662646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:01.929605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.78479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418771"} {"level":"info","ts":"2026-09-01T06:01:01.929701Z","caller":"traceutil/trace.go:171","msg":"trace[463942487] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:21929; }","duration":"604.887293ms","start":"2026-09-01T06:01:01.324786Z","end":"2026-09-01T06:01:01.929673Z","steps":["trace[463942487] 'agreement among raft nodes before linearized reading' (duration: 596.31588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:01.929772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:01.324745Z","time spent":"604.999586ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418795,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-09-01T06:01:02.365769Z","caller":"traceutil/trace.go:171","msg":"trace[1212659112] linearizableReadLoop","detail":"{readStateIndex:22678; appliedIndex:22677; }","duration":"107.324246ms","start":"2026-09-01T06:01:02.258421Z","end":"2026-09-01T06:01:02.365745Z","steps":["trace[1212659112] 'read index received' (duration: 107.002156ms)","trace[1212659112] 'applied index is now lower than readState.Index' (duration: 321.2µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:02.366153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.701357ms","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-09-01T06:01:02.366266Z","caller":"traceutil/trace.go:171","msg":"trace[736736114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21930; }","duration":"107.868642ms","start":"2026-09-01T06:01:02.258378Z","end":"2026-09-01T06:01:02.366247Z","steps":["trace[736736114] 'agreement among raft nodes before linearized reading' (duration: 107.596534ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:02.366435Z","caller":"traceutil/trace.go:171","msg":"trace[903274987] transaction","detail":"{read_only:false; response_revision:21930; number_of_response:1; }","duration":"146.048758ms","start":"2026-09-01T06:01:02.220373Z","end":"2026-09-01T06:01:02.366422Z","steps":["trace[903274987] 'process raft request' (duration: 145.172012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:02.663509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.138771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-01T06:01:02.663616Z","caller":"traceutil/trace.go:171","msg":"trace[269288524] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:21931; }","duration":"245.304006ms","start":"2026-09-01T06:01:02.418287Z","end":"2026-09-01T06:01:02.663591Z","steps":["trace[269288524] 'range keys from in-memory index tree' (duration: 244.879684ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:02.902684Z","caller":"traceutil/trace.go:171","msg":"trace[1341007285] linearizableReadLoop","detail":"{readStateIndex:22680; appliedIndex:22679; }","duration":"236.039092ms","start":"2026-09-01T06:01:02.666606Z","end":"2026-09-01T06:01:02.902646Z","steps":["trace[1341007285] 'read index received' (duration: 235.61102ms)","trace[1341007285] 'applied index is now lower than readState.Index' (duration: 426.332µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:02.902758Z","caller":"traceutil/trace.go:171","msg":"trace[2108273847] transaction","detail":"{read_only:false; response_revision:21932; number_of_response:1; }","duration":"332.049595ms","start":"2026-09-01T06:01:02.570655Z","end":"2026-09-01T06:01:02.902705Z","steps":["trace[2108273847] 'process raft request' (duration: 331.625742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:02.902948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:02.570613Z","time spent":"332.23627ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418529,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:02.903021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.370433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-44af5e52-8e5a-4ded-9d1b-b90599552115\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-09-01T06:01:02.903127Z","caller":"traceutil/trace.go:171","msg":"trace[1864027410] range","detail":"{range_begin:/registry/persistentvolumes/pvc-44af5e52-8e5a-4ded-9d1b-b90599552115; range_end:; response_count:1; response_revision:21932; }","duration":"236.541728ms","start":"2026-09-01T06:01:02.666558Z","end":"2026-09-01T06:01:02.9031Z","steps":["trace[1864027410] 'agreement among raft nodes before linearized reading' (duration: 236.213008ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:03.303725Z","caller":"traceutil/trace.go:171","msg":"trace[2014192615] linearizableReadLoop","detail":"{readStateIndex:22681; appliedIndex:22680; }","duration":"110.53182ms","start":"2026-09-01T06:01:03.193157Z","end":"2026-09-01T06:01:03.303689Z","steps":["trace[2014192615] 'read index received' (duration: 110.20279ms)","trace[2014192615] 'applied index is now lower than readState.Index' (duration: 327.75µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:03.303766Z","caller":"traceutil/trace.go:171","msg":"trace[567278799] transaction","detail":"{read_only:false; response_revision:21933; number_of_response:1; }","duration":"156.343531ms","start":"2026-09-01T06:01:03.147395Z","end":"2026-09-01T06:01:03.303739Z","steps":["trace[567278799] 'process raft request' (duration: 156.080194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:03.303896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.711686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:01:03.303992Z","caller":"traceutil/trace.go:171","msg":"trace[27411616] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:21933; }","duration":"110.896552ms","start":"2026-09-01T06:01:03.193081Z","end":"2026-09-01T06:01:03.303977Z","steps":["trace[27411616] 'agreement among raft nodes before linearized reading' (duration: 110.732017ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:05:03.577571Z","caller":"traceutil/trace.go:171","msg":"trace[628184623] transaction","detail":"{read_only:false; response_revision:23922; number_of_response:1; }","duration":"148.304354ms","start":"2026-09-01T06:05:03.429241Z","end":"2026-09-01T06:05:03.577545Z","steps":["trace[628184623] 'process raft request' (duration: 148.15072ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:05:03.578823Z","caller":"traceutil/trace.go:171","msg":"trace[2128028921] transaction","detail":"{read_only:false; response_revision:23923; number_of_response:1; }","duration":"106.420009ms","start":"2026-09-01T06:05:03.472368Z","end":"2026-09-01T06:05:03.578788Z","steps":["trace[2128028921] 'process raft request' (duration: 106.13148ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:05:40.089293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21815} {"level":"info","ts":"2026-09-01T06:05:40.218184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21815,"took":"125.055249ms","hash":1175899847,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":43442176,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T06:05:40.218272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1175899847,"revision":21815,"compact-revision":19681} {"level":"info","ts":"2026-09-01T06:06:26.499621Z","caller":"traceutil/trace.go:171","msg":"trace[73934773] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"124.162883ms","start":"2026-09-01T06:06:26.375429Z","end":"2026-09-01T06:06:26.499592Z","steps":["trace[73934773] 'process raft request' (duration: 123.962887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:06:36.139798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.817342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:06:36.139927Z","caller":"traceutil/trace.go:171","msg":"trace[1521323297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25389; }","duration":"145.985146ms","start":"2026-09-01T06:06:35.993922Z","end":"2026-09-01T06:06:36.139907Z","steps":["trace[1521323297] 'range keys from in-memory index tree' (duration: 145.743279ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:08:42.013061Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a47.wal"} {"level":"info","ts":"2026-09-01T06:10:40.09629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24775} {"level":"info","ts":"2026-09-01T06:10:40.251976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24775,"took":"151.447277ms","hash":1533494151,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":34131968,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T06:10:40.252048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1533494151,"revision":24775,"compact-revision":21815} {"level":"info","ts":"2026-09-01T06:14:50.704305Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"865c2c1f02504fb9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T06:14:50.710725Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T06:14:50.710807Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T06:15:40.104292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27228} {"level":"info","ts":"2026-09-01T06:15:40.233818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27228,"took":"124.284758ms","hash":2258661015,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":31035392,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T06:15:40.233882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2258661015,"revision":27228,"compact-revision":24775}