{"level":"warn","ts":"2026-09-11T00:29:51.911333Z","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-11T00:29:51.911467Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.15: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.15:2380","--initial-cluster=instance=https://162.253.55.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.15: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-11T00:29:51.911554Z","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-11T00:29:51.911567Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-09-11T00:29:51.911593Z","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-11T00:29:51.912421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"]} {"level":"info","ts":"2026-09-11T00:29:51.91254Z","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.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.15: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-11T00:29:51.916322Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.517017ms"} {"level":"info","ts":"2026-09-11T00:29:51.929244Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"337baeb286292d09","cluster-id":"3cc18487e1df591c"} {"level":"info","ts":"2026-09-11T00:29:51.929411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T00:29:51.929473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 0"} {"level":"info","ts":"2026-09-11T00:29:51.929502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 337baeb286292d09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T00:29:51.929521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 1"} {"level":"info","ts":"2026-09-11T00:29:51.929585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"warn","ts":"2026-09-11T00:29:51.934927Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T00:29:51.9369Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T00:29:51.938387Z","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-11T00:29:51.941376Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"337baeb286292d09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T00:29:51.941761Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"337baeb286292d09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T00:29:51.941884Z","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-11T00:29:51.942164Z","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-11T00:29:51.942292Z","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-11T00:29:51.950008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"info","ts":"2026-09-11T00:29:51.950789Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","added-peer-id":"337baeb286292d09","added-peer-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-09-11T00:29:51.953667Z","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-11T00:29:51.953878Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-09-11T00:29:51.953951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-09-11T00:29:51.954296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"337baeb286292d09","initial-advertise-peer-urls":["https://162.253.55.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T00:29:51.954396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T00:29:52.230264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T00:29:52.230369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T00:29:52.230424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgPreVoteResp from 337baeb286292d09 at term 1"} {"level":"info","ts":"2026-09-11T00:29:52.230468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became candidate at term 2"} {"level":"info","ts":"2026-09-11T00:29:52.230487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgVoteResp from 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-09-11T00:29:52.230501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became leader at term 2"} {"level":"info","ts":"2026-09-11T00:29:52.230515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 337baeb286292d09 elected leader 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-09-11T00:29:52.231623Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:29:52.232752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:29:52.232761Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"337baeb286292d09","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.15:2379]}","request-path":"/0/members/337baeb286292d09/attributes","cluster-id":"3cc18487e1df591c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T00:29:52.232903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:29:52.233321Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:29:52.23351Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:29:52.233516Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T00:29:52.233551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T00:29:52.233561Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:29:52.236865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T00:29:52.240168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.15:2379"} {"level":"info","ts":"2026-09-11T00:30:08.872025Z","caller":"traceutil/trace.go:171","msg":"trace[2076450598] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"114.972861ms","start":"2026-09-11T00:30:08.756971Z","end":"2026-09-11T00:30:08.871944Z","steps":["trace[2076450598] 'process raft request' (duration: 114.583813ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:30:08.990721Z","caller":"traceutil/trace.go:171","msg":"trace[1887718223] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"113.770396ms","start":"2026-09-11T00:30:08.876917Z","end":"2026-09-11T00:30:08.990688Z","steps":["trace[1887718223] 'process raft request' (duration: 87.789284ms)","trace[1887718223] 'compare' (duration: 25.813758ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:30:38.966073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.042333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sfpgn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-11T00:30:38.966909Z","caller":"traceutil/trace.go:171","msg":"trace[790563984] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sfpgn; range_end:; response_count:1; response_revision:639; }","duration":"121.268127ms","start":"2026-09-11T00:30:38.844911Z","end":"2026-09-11T00:30:38.966179Z","steps":["trace[790563984] 'range keys from in-memory index tree' (duration: 120.823888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:30:39.383787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.214262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:30:39.383983Z","caller":"traceutil/trace.go:171","msg":"trace[1586877152] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"216.779959ms","start":"2026-09-11T00:30:39.167182Z","end":"2026-09-11T00:30:39.383962Z","steps":["trace[1586877152] 'read index received' (duration: 9.365072ms)","trace[1586877152] 'applied index is now lower than readState.Index' (duration: 207.413397ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:30:39.384181Z","caller":"traceutil/trace.go:171","msg":"trace[4977327] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"306.072756ms","start":"2026-09-11T00:30:39.078062Z","end":"2026-09-11T00:30:39.384135Z","steps":["trace[4977327] 'process raft request' (duration: 98.588167ms)","trace[4977327] 'compare' (duration: 205.892455ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:30:39.384336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.155387ms","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-11T00:30:39.38437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:30:39.078031Z","time spent":"306.24526ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:30:39.384415Z","caller":"traceutil/trace.go:171","msg":"trace[2044537142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"217.30125ms","start":"2026-09-11T00:30:39.167102Z","end":"2026-09-11T00:30:39.384403Z","steps":["trace[2044537142] 'agreement among raft nodes before linearized reading' (duration: 216.973823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:30:39.856285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.820466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:30:39.856385Z","caller":"traceutil/trace.go:171","msg":"trace[1069235202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"169.997849ms","start":"2026-09-11T00:30:39.686365Z","end":"2026-09-11T00:30:39.856363Z","steps":["trace[1069235202] 'range keys from in-memory index tree' (duration: 169.720273ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:30:56.319975Z","caller":"traceutil/trace.go:171","msg":"trace[1339204586] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"124.712503ms","start":"2026-09-11T00:30:56.195215Z","end":"2026-09-11T00:30:56.319927Z","steps":["trace[1339204586] 'process raft request' (duration: 124.486128ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:30:56.473295Z","caller":"traceutil/trace.go:171","msg":"trace[1134775793] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"148.916535ms","start":"2026-09-11T00:30:56.324345Z","end":"2026-09-11T00:30:56.473262Z","steps":["trace[1134775793] 'process raft request' (duration: 148.785232ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:41.847873Z","caller":"traceutil/trace.go:171","msg":"trace[490479597] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"110.86629ms","start":"2026-09-11T00:31:41.736983Z","end":"2026-09-11T00:31:41.847849Z","steps":["trace[490479597] 'process raft request' (duration: 110.727587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:33:07.455489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.972261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:33:07.45573Z","caller":"traceutil/trace.go:171","msg":"trace[1835796835] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"178.759229ms","start":"2026-09-11T00:33:07.276938Z","end":"2026-09-11T00:33:07.455697Z","steps":["trace[1835796835] 'process raft request' (duration: 46.284797ms)","trace[1835796835] 'compare' (duration: 131.757406ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:33:07.735839Z","caller":"traceutil/trace.go:171","msg":"trace[1217869486] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"124.152703ms","start":"2026-09-11T00:33:07.611658Z","end":"2026-09-11T00:33:07.735811Z","steps":["trace[1217869486] 'process raft request' (duration: 123.962669ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:33:08.042606Z","caller":"traceutil/trace.go:171","msg":"trace[791743345] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"197.358168ms","start":"2026-09-11T00:33:07.845225Z","end":"2026-09-11T00:33:08.042583Z","steps":["trace[791743345] 'read index received' (duration: 129.15156ms)","trace[791743345] 'applied index is now lower than readState.Index' (duration: 68.205678ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:33:08.042638Z","caller":"traceutil/trace.go:171","msg":"trace[1715773897] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"300.318035ms","start":"2026-09-11T00:33:07.742251Z","end":"2026-09-11T00:33:08.042569Z","steps":["trace[1715773897] 'process raft request' (duration: 232.108937ms)","trace[1715773897] 'compare' (duration: 68.020094ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:33:08.042907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.660726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T00:33:08.042974Z","caller":"traceutil/trace.go:171","msg":"trace[311686369] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2230; }","duration":"197.781188ms","start":"2026-09-11T00:33:07.845177Z","end":"2026-09-11T00:33:08.042959Z","steps":["trace[311686369] 'agreement among raft nodes before linearized reading' (duration: 197.468451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:33:08.042954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:33:07.742226Z","time spent":"300.53448ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:33:08.043019Z","caller":"traceutil/trace.go:171","msg":"trace[179108377] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"160.718351ms","start":"2026-09-11T00:33:07.882267Z","end":"2026-09-11T00:33:08.042985Z","steps":["trace[179108377] 'process raft request' (duration: 160.527267ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:33:08.043627Z","caller":"traceutil/trace.go:171","msg":"trace[917401009] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"178.890872ms","start":"2026-09-11T00:33:07.864693Z","end":"2026-09-11T00:33:08.043584Z","steps":["trace[917401009] 'process raft request' (duration: 177.844208ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:33:13.381342Z","caller":"traceutil/trace.go:171","msg":"trace[191194566] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"120.667548ms","start":"2026-09-11T00:33:13.260644Z","end":"2026-09-11T00:33:13.381312Z","steps":["trace[191194566] 'process raft request' (duration: 120.478344ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:34:37.89766Z","caller":"traceutil/trace.go:171","msg":"trace[31971784] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"154.352741ms","start":"2026-09-11T00:34:37.743277Z","end":"2026-09-11T00:34:37.897629Z","steps":["trace[31971784] 'process raft request' (duration: 153.544454ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:39:38.575358Z","caller":"traceutil/trace.go:171","msg":"trace[714441396] linearizableReadLoop","detail":"{readStateIndex:5501; appliedIndex:5500; }","duration":"228.627102ms","start":"2026-09-11T00:39:38.346703Z","end":"2026-09-11T00:39:38.57533Z","steps":["trace[714441396] 'read index received' (duration: 228.53444ms)","trace[714441396] 'applied index is now lower than readState.Index' (duration: 91.082µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:39:38.575515Z","caller":"traceutil/trace.go:171","msg":"trace[375529209] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"229.932051ms","start":"2026-09-11T00:39:38.345521Z","end":"2026-09-11T00:39:38.575453Z","steps":["trace[375529209] 'process raft request' (duration: 229.628064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:38.575665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.930778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-11T00:39:38.575717Z","caller":"traceutil/trace.go:171","msg":"trace[1281861989] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5300; }","duration":"229.013361ms","start":"2026-09-11T00:39:38.346689Z","end":"2026-09-11T00:39:38.575702Z","steps":["trace[1281861989] 'agreement among raft nodes before linearized reading' (duration: 228.835787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:38.57598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.326928ms","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-11T00:39:38.576162Z","caller":"traceutil/trace.go:171","msg":"trace[1743055417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5300; }","duration":"215.589874ms","start":"2026-09-11T00:39:38.360545Z","end":"2026-09-11T00:39:38.576135Z","steps":["trace[1743055417] 'agreement among raft nodes before linearized reading' (duration: 215.211655ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:39:42.734856Z","caller":"traceutil/trace.go:171","msg":"trace[1616726016] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5634; }","duration":"132.193259ms","start":"2026-09-11T00:39:42.602637Z","end":"2026-09-11T00:39:42.73483Z","steps":["trace[1616726016] 'read index received' (duration: 132.019035ms)","trace[1616726016] 'applied index is now lower than readState.Index' (duration: 172.924µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:39:42.735034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.379873ms","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-11T00:39:42.734971Z","caller":"traceutil/trace.go:171","msg":"trace[2003387807] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"132.641019ms","start":"2026-09-11T00:39:42.602272Z","end":"2026-09-11T00:39:42.734913Z","steps":["trace[2003387807] 'process raft request' (duration: 132.401704ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:39:42.735084Z","caller":"traceutil/trace.go:171","msg":"trace[375000597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5429; }","duration":"132.477835ms","start":"2026-09-11T00:39:42.602593Z","end":"2026-09-11T00:39:42.735071Z","steps":["trace[375000597] 'agreement among raft nodes before linearized reading' (duration: 132.328122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:42.735096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.398763ms","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-11T00:39:42.735139Z","caller":"traceutil/trace.go:171","msg":"trace[1235932603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5429; }","duration":"132.474845ms","start":"2026-09-11T00:39:42.602651Z","end":"2026-09-11T00:39:42.735126Z","steps":["trace[1235932603] 'agreement among raft nodes before linearized reading' (duration: 132.345962ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:39:52.611571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-09-11T00:39:52.699924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"86.768909ms","hash":2251671654,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":30969856,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-11T00:39:52.700031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2251671654,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-09-11T00:39:58.629008Z","caller":"traceutil/trace.go:171","msg":"trace[1843764591] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"104.748965ms","start":"2026-09-11T00:39:58.524232Z","end":"2026-09-11T00:39:58.628981Z","steps":["trace[1843764591] 'process raft request' (duration: 104.570951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:59.079147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.899219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:39:59.079299Z","caller":"traceutil/trace.go:171","msg":"trace[443935139] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"444.826849ms","start":"2026-09-11T00:39:58.634445Z","end":"2026-09-11T00:39:59.079272Z","steps":["trace[443935139] 'process raft request' (duration: 154.675913ms)","trace[443935139] 'compare' (duration: 289.768647ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:39:59.079385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:39:58.634395Z","time spent":"444.946071ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":990,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:39:59.103073Z","caller":"traceutil/trace.go:171","msg":"trace[2017347571] linearizableReadLoop","detail":"{readStateIndex:5942; appliedIndex:5940; }","duration":"284.091945ms","start":"2026-09-11T00:39:58.818965Z","end":"2026-09-11T00:39:59.103057Z","steps":["trace[2017347571] 'read index received' (duration: 283.86256ms)","trace[2017347571] 'applied index is now lower than readState.Index' (duration: 228.675µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:39:59.103209Z","caller":"traceutil/trace.go:171","msg":"trace[916641853] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"461.789651ms","start":"2026-09-11T00:39:58.641395Z","end":"2026-09-11T00:39:59.103185Z","steps":["trace[916641853] 'process raft request' (duration: 461.581097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:59.103264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.080709ms","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-11T00:39:59.103319Z","caller":"traceutil/trace.go:171","msg":"trace[1665677708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5726; }","duration":"245.178401ms","start":"2026-09-11T00:39:58.858129Z","end":"2026-09-11T00:39:59.103307Z","steps":["trace[1665677708] 'agreement among raft nodes before linearized reading' (duration: 245.028318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:59.103374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.395362ms","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-11T00:39:59.103446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:39:58.641384Z","time spent":"461.933125ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:39:59.103473Z","caller":"traceutil/trace.go:171","msg":"trace[1047430367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5726; }","duration":"284.540694ms","start":"2026-09-11T00:39:58.818911Z","end":"2026-09-11T00:39:59.103452Z","steps":["trace[1047430367] 'agreement among raft nodes before linearized reading' (duration: 284.243458ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:39:59.103866Z","caller":"traceutil/trace.go:171","msg":"trace[82865317] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"466.921532ms","start":"2026-09-11T00:39:58.63621Z","end":"2026-09-11T00:39:59.103131Z","steps":["trace[82865317] 'process raft request' (duration: 466.666657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:39:59.10395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:39:58.636197Z","time spent":"467.711348ms","remote":"127.0.0.1:44568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1561,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:39:59.103782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.503538ms","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-11T00:39:59.104306Z","caller":"traceutil/trace.go:171","msg":"trace[28168446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5726; }","duration":"168.07799ms","start":"2026-09-11T00:39:58.936205Z","end":"2026-09-11T00:39:59.104283Z","steps":["trace[28168446] 'agreement among raft nodes before linearized reading' (duration: 167.063009ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:50.755074Z","caller":"traceutil/trace.go:171","msg":"trace[202780270] transaction","detail":"{read_only:false; response_revision:7468; number_of_response:1; }","duration":"121.333426ms","start":"2026-09-11T00:43:50.633714Z","end":"2026-09-11T00:43:50.755047Z","steps":["trace[202780270] 'process raft request' (duration: 99.553401ms)","trace[202780270] 'compare' (duration: 21.620312ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:43:50.755229Z","caller":"traceutil/trace.go:171","msg":"trace[12887204] linearizableReadLoop","detail":"{readStateIndex:7743; appliedIndex:7740; }","duration":"117.361131ms","start":"2026-09-11T00:43:50.637839Z","end":"2026-09-11T00:43:50.7552Z","steps":["trace[12887204] 'read index received' (duration: 24.897172ms)","trace[12887204] 'applied index is now lower than readState.Index' (duration: 92.462289ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:43:50.75529Z","caller":"traceutil/trace.go:171","msg":"trace[560823056] transaction","detail":"{read_only:false; response_revision:7469; number_of_response:1; }","duration":"121.346738ms","start":"2026-09-11T00:43:50.633901Z","end":"2026-09-11T00:43:50.755248Z","steps":["trace[560823056] 'process raft request' (duration: 121.105932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:50.75561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.74075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-11T00:43:50.755731Z","caller":"traceutil/trace.go:171","msg":"trace[2131408639] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:7469; }","duration":"117.908283ms","start":"2026-09-11T00:43:50.637793Z","end":"2026-09-11T00:43:50.755701Z","steps":["trace[2131408639] 'agreement among raft nodes before linearized reading' (duration: 117.524065ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:52.620761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5641} {"level":"info","ts":"2026-09-11T00:44:52.704641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5641,"took":"81.838508ms","hash":751042549,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":27906048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T00:44:52.704798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":751042549,"revision":5641,"compact-revision":2990} {"level":"warn","ts":"2026-09-11T00:46:51.538082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.478314ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-11T00:46:51.538613Z","caller":"traceutil/trace.go:171","msg":"trace[276101776] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"222.617773ms","start":"2026-09-11T00:46:51.315963Z","end":"2026-09-11T00:46:51.538581Z","steps":["trace[276101776] 'process raft request' (duration: 222.311936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.006891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.729977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007002Z","caller":"traceutil/trace.go:171","msg":"trace[1671780919] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8963; }","duration":"113.895931ms","start":"2026-09-11T00:47:03.893083Z","end":"2026-09-11T00:47:04.006979Z","steps":["trace[1671780919] 'range keys from in-memory index tree' (duration: 113.621506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.002658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007344Z","caller":"traceutil/trace.go:171","msg":"trace[373772494] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8963; }","duration":"155.09909ms","start":"2026-09-11T00:47:03.852221Z","end":"2026-09-11T00:47:04.007321Z","steps":["trace[373772494] 'range keys from in-memory index tree' (duration: 154.924327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.613476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007474Z","caller":"traceutil/trace.go:171","msg":"trace[111863285] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8963; }","duration":"113.773448ms","start":"2026-09-11T00:47:03.893681Z","end":"2026-09-11T00:47:04.007454Z","steps":["trace[111863285] 'range keys from in-memory index tree' (duration: 113.541643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.200418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007596Z","caller":"traceutil/trace.go:171","msg":"trace[396740739] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8963; }","duration":"134.281001ms","start":"2026-09-11T00:47:03.873301Z","end":"2026-09-11T00:47:04.007582Z","steps":["trace[396740739] 'count revisions from in-memory index tree' (duration: 134.161208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.402818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.00775Z","caller":"traceutil/trace.go:171","msg":"trace[1277889287] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8963; }","duration":"156.571402ms","start":"2026-09-11T00:47:03.851158Z","end":"2026-09-11T00:47:04.007729Z","steps":["trace[1277889287] 'count revisions from in-memory index tree' (duration: 156.286115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.00774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.600676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-11T00:47:04.00782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.564287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T00:47:04.007889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.742045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007897Z","caller":"traceutil/trace.go:171","msg":"trace[1989213594] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8963; }","duration":"134.652209ms","start":"2026-09-11T00:47:03.87322Z","end":"2026-09-11T00:47:04.007873Z","steps":["trace[1989213594] 'range keys from in-memory index tree' (duration: 134.428554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.71193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.007948Z","caller":"traceutil/trace.go:171","msg":"trace[31796891] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8963; }","duration":"133.780681ms","start":"2026-09-11T00:47:03.874158Z","end":"2026-09-11T00:47:04.007938Z","steps":["trace[31796891] 'range keys from in-memory index tree' (duration: 133.595687ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:04.007968Z","caller":"traceutil/trace.go:171","msg":"trace[1785451634] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8963; }","duration":"156.843807ms","start":"2026-09-11T00:47:03.851082Z","end":"2026-09-11T00:47:04.007926Z","steps":["trace[1785451634] 'range keys from in-memory index tree' (duration: 156.658304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.666608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.008127Z","caller":"traceutil/trace.go:171","msg":"trace[870450411] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8963; }","duration":"115.033335ms","start":"2026-09-11T00:47:03.893065Z","end":"2026-09-11T00:47:04.008098Z","steps":["trace[870450411] 'count revisions from in-memory index tree' (duration: 114.558396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.008141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.023243ms","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-11T00:47:04.008204Z","caller":"traceutil/trace.go:171","msg":"trace[375450277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8963; }","duration":"121.106784ms","start":"2026-09-11T00:47:03.887083Z","end":"2026-09-11T00:47:04.008189Z","steps":["trace[375450277] 'range keys from in-memory index tree' (duration: 120.879189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.008201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.585635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T00:47:04.008219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.465013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.00825Z","caller":"traceutil/trace.go:171","msg":"trace[1773095057] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8963; }","duration":"186.680956ms","start":"2026-09-11T00:47:03.821558Z","end":"2026-09-11T00:47:04.008239Z","steps":["trace[1773095057] 'range keys from in-memory index tree' (duration: 186.486373ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:04.00826Z","caller":"traceutil/trace.go:171","msg":"trace[1584012761] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8963; }","duration":"186.570344ms","start":"2026-09-11T00:47:03.821677Z","end":"2026-09-11T00:47:04.008247Z","steps":["trace[1584012761] 'count revisions from in-memory index tree' (duration: 186.343819ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:04.007823Z","caller":"traceutil/trace.go:171","msg":"trace[679648640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8963; }","duration":"113.738838ms","start":"2026-09-11T00:47:03.894065Z","end":"2026-09-11T00:47:04.007804Z","steps":["trace[679648640] 'range keys from in-memory index tree' (duration: 113.39487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.132926ms","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-11T00:47:04.008536Z","caller":"traceutil/trace.go:171","msg":"trace[855625577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8963; }","duration":"114.009445ms","start":"2026-09-11T00:47:03.894481Z","end":"2026-09-11T00:47:04.00849Z","steps":["trace[855625577] 'range keys from in-memory index tree' (duration: 112.978852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.007312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.038842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:47:04.011878Z","caller":"traceutil/trace.go:171","msg":"trace[933304003] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8963; }","duration":"189.637269ms","start":"2026-09-11T00:47:03.8222Z","end":"2026-09-11T00:47:04.011837Z","steps":["trace[933304003] 'range keys from in-memory index tree' (duration: 184.916429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:47:04.486044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.701449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:47:04.486319Z","caller":"traceutil/trace.go:171","msg":"trace[1657763046] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"148.704105ms","start":"2026-09-11T00:47:04.337595Z","end":"2026-09-11T00:47:04.486299Z","steps":["trace[1657763046] 'process raft request' (duration: 148.581662ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:04.486311Z","caller":"traceutil/trace.go:171","msg":"trace[1847151015] transaction","detail":"{read_only:false; response_revision:8968; number_of_response:1; }","duration":"208.406214ms","start":"2026-09-11T00:47:04.277852Z","end":"2026-09-11T00:47:04.486258Z","steps":["trace[1847151015] 'process raft request' (duration: 93.365388ms)","trace[1847151015] 'compare' (duration: 114.483455ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:47:29.581516Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002506.wal"} {"level":"warn","ts":"2026-09-11T00:47:36.527062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.025786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:47:36.527498Z","caller":"traceutil/trace.go:171","msg":"trace[255989398] transaction","detail":"{read_only:false; response_revision:9194; number_of_response:1; }","duration":"232.447741ms","start":"2026-09-11T00:47:36.294939Z","end":"2026-09-11T00:47:36.527387Z","steps":["trace[255989398] 'process raft request' (duration: 23.936155ms)","trace[255989398] 'compare' (duration: 207.781291ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:47:36.670309Z","caller":"traceutil/trace.go:171","msg":"trace[1673351411] transaction","detail":"{read_only:false; response_revision:9195; number_of_response:1; }","duration":"133.943885ms","start":"2026-09-11T00:47:36.536331Z","end":"2026-09-11T00:47:36.670275Z","steps":["trace[1673351411] 'process raft request' (duration: 126.425186ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:44.683177Z","caller":"traceutil/trace.go:171","msg":"trace[1575226417] transaction","detail":"{read_only:false; response_revision:9236; number_of_response:1; }","duration":"108.208151ms","start":"2026-09-11T00:47:44.574925Z","end":"2026-09-11T00:47:44.683133Z","steps":["trace[1575226417] 'process raft request' (duration: 107.969586ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:44.852994Z","caller":"traceutil/trace.go:171","msg":"trace[614380678] linearizableReadLoop","detail":"{readStateIndex:9570; appliedIndex:9569; }","duration":"131.046953ms","start":"2026-09-11T00:47:44.721913Z","end":"2026-09-11T00:47:44.85296Z","steps":["trace[614380678] 'read index received' (duration: 88.608489ms)","trace[614380678] 'applied index is now lower than readState.Index' (duration: 42.436354ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:47:44.853171Z","caller":"traceutil/trace.go:171","msg":"trace[1652971166] transaction","detail":"{read_only:false; response_revision:9237; number_of_response:1; }","duration":"141.191346ms","start":"2026-09-11T00:47:44.711958Z","end":"2026-09-11T00:47:44.853149Z","steps":["trace[1652971166] 'process raft request' (duration: 98.560418ms)","trace[1652971166] 'compare' (duration: 42.314922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:47:44.853392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.461112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-11T00:47:44.853488Z","caller":"traceutil/trace.go:171","msg":"trace[956648015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9237; }","duration":"131.614795ms","start":"2026-09-11T00:47:44.721857Z","end":"2026-09-11T00:47:44.853471Z","steps":["trace[956648015] 'agreement among raft nodes before linearized reading' (duration: 131.37583ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:47.617099Z","caller":"traceutil/trace.go:171","msg":"trace[2140869285] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9259; }","duration":"124.164759ms","start":"2026-09-11T00:47:47.49291Z","end":"2026-09-11T00:47:47.617075Z","steps":["trace[2140869285] 'process raft request' (duration: 123.350021ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:47.664638Z","caller":"traceutil/trace.go:171","msg":"trace[640339272] transaction","detail":"{read_only:false; response_revision:9260; number_of_response:1; }","duration":"171.364524ms","start":"2026-09-11T00:47:47.493242Z","end":"2026-09-11T00:47:47.664607Z","steps":["trace[640339272] 'process raft request' (duration: 171.116939ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:48:26.944584Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T00:48:26.952283Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-11T00:48:26.952513Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-11T00:49:52.629342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7965} {"level":"info","ts":"2026-09-11T00:49:52.734038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7965,"took":"101.467344ms","hash":4284230187,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":27811840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T00:49:52.734154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4284230187,"revision":7965,"compact-revision":5641} {"level":"info","ts":"2026-09-11T00:54:35.642116Z","caller":"traceutil/trace.go:171","msg":"trace[676100143] transaction","detail":"{read_only:false; response_revision:12434; number_of_response:1; }","duration":"107.629418ms","start":"2026-09-11T00:54:35.534457Z","end":"2026-09-11T00:54:35.642086Z","steps":["trace[676100143] 'process raft request' (duration: 107.462345ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:54:35.703545Z","caller":"traceutil/trace.go:171","msg":"trace[1066687006] transaction","detail":"{read_only:false; response_revision:12435; number_of_response:1; }","duration":"161.935572ms","start":"2026-09-11T00:54:35.541574Z","end":"2026-09-11T00:54:35.703509Z","steps":["trace[1066687006] 'process raft request' (duration: 161.742038ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:54:52.636818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10360} {"level":"info","ts":"2026-09-11T00:54:52.728207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10360,"took":"87.498719ms","hash":1697253527,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":25702400,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T00:54:52.728278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1697253527,"revision":10360,"compact-revision":7965} {"level":"info","ts":"2026-09-11T00:58:55.060861Z","caller":"traceutil/trace.go:171","msg":"trace[1439172313] transaction","detail":"{read_only:false; response_revision:14094; number_of_response:1; }","duration":"177.99586ms","start":"2026-09-11T00:58:54.882831Z","end":"2026-09-11T00:58:55.060827Z","steps":["trace[1439172313] 'process raft request' (duration: 177.801406ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:55.071518Z","caller":"traceutil/trace.go:171","msg":"trace[579635559] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"188.564885ms","start":"2026-09-11T00:58:54.882933Z","end":"2026-09-11T00:58:55.071498Z","steps":["trace[579635559] 'process raft request' (duration: 188.425952ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:55.071698Z","caller":"traceutil/trace.go:171","msg":"trace[587485580] linearizableReadLoop","detail":"{readStateIndex:14587; appliedIndex:14584; }","duration":"163.784207ms","start":"2026-09-11T00:58:54.907893Z","end":"2026-09-11T00:58:55.071678Z","steps":["trace[587485580] 'read index received' (duration: 152.66409ms)","trace[587485580] 'applied index is now lower than readState.Index' (duration: 11.118497ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:58:55.071916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.015072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qjbnl\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-09-11T00:58:55.071957Z","caller":"traceutil/trace.go:171","msg":"trace[449313891] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qjbnl; range_end:; response_count:1; response_revision:14096; }","duration":"164.132095ms","start":"2026-09-11T00:58:54.907814Z","end":"2026-09-11T00:58:55.071946Z","steps":["trace[449313891] 'agreement among raft nodes before linearized reading' (duration: 163.933691ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:55.071886Z","caller":"traceutil/trace.go:171","msg":"trace[1497857374] transaction","detail":"{read_only:false; response_revision:14096; number_of_response:1; }","duration":"187.972192ms","start":"2026-09-11T00:58:54.883839Z","end":"2026-09-11T00:58:55.071811Z","steps":["trace[1497857374] 'process raft request' (duration: 187.620415ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:52.645148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12529} {"level":"info","ts":"2026-09-11T00:59:52.736545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12529,"took":"89.009174ms","hash":2683647322,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T00:59:52.73664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2683647322,"revision":12529,"compact-revision":10360} {"level":"info","ts":"2026-09-11T01:01:19.687963Z","caller":"traceutil/trace.go:171","msg":"trace[1503837922] linearizableReadLoop","detail":"{readStateIndex:15645; appliedIndex:15644; }","duration":"160.466066ms","start":"2026-09-11T01:01:19.527475Z","end":"2026-09-11T01:01:19.687941Z","steps":["trace[1503837922] 'read index received' (duration: 160.321423ms)","trace[1503837922] 'applied index is now lower than readState.Index' (duration: 143.283µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:01:19.688181Z","caller":"traceutil/trace.go:171","msg":"trace[657843676] transaction","detail":"{read_only:false; response_revision:15116; number_of_response:1; }","duration":"187.604214ms","start":"2026-09-11T01:01:19.500529Z","end":"2026-09-11T01:01:19.688133Z","steps":["trace[657843676] 'process raft request' (duration: 187.277237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:19.688272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.756952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-11T01:01:19.688317Z","caller":"traceutil/trace.go:171","msg":"trace[1169353994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15116; }","duration":"160.865645ms","start":"2026-09-11T01:01:19.52744Z","end":"2026-09-11T01:01:19.688305Z","steps":["trace[1169353994] 'agreement among raft nodes before linearized reading' (duration: 160.674921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:19.688395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.604139ms","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-11T01:01:19.688531Z","caller":"traceutil/trace.go:171","msg":"trace[1723139746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15116; }","duration":"160.766442ms","start":"2026-09-11T01:01:19.527703Z","end":"2026-09-11T01:01:19.688469Z","steps":["trace[1723139746] 'agreement among raft nodes before linearized reading' (duration: 160.459756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:37.809917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T01:01:37.810035Z","caller":"traceutil/trace.go:171","msg":"trace[405755993] linearizableReadLoop","detail":"{readStateIndex:15760; appliedIndex:15759; }","duration":"250.062524ms","start":"2026-09-11T01:01:37.559955Z","end":"2026-09-11T01:01:37.810018Z","steps":["trace[405755993] 'read index received' (duration: 96.658199ms)","trace[405755993] 'applied index is now lower than readState.Index' (duration: 153.402985ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:01:37.810092Z","caller":"traceutil/trace.go:171","msg":"trace[2112206808] transaction","detail":"{read_only:false; response_revision:15225; number_of_response:1; }","duration":"367.652197ms","start":"2026-09-11T01:01:37.442429Z","end":"2026-09-11T01:01:37.810081Z","steps":["trace[2112206808] 'process raft request' (duration: 214.16961ms)","trace[2112206808] 'compare' (duration: 153.001167ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:01:37.810135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:01:37.442413Z","time spent":"367.700718ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:01:37.810564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.395291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T01:01:37.810674Z","caller":"traceutil/trace.go:171","msg":"trace[1654381243] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:15225; }","duration":"250.738919ms","start":"2026-09-11T01:01:37.559913Z","end":"2026-09-11T01:01:37.810652Z","steps":["trace[1654381243] 'agreement among raft nodes before linearized reading' (duration: 250.281399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:37.879849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.428521ms","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-11T01:01:37.880202Z","caller":"traceutil/trace.go:171","msg":"trace[782071851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15228; }","duration":"110.819889ms","start":"2026-09-11T01:01:37.76935Z","end":"2026-09-11T01:01:37.88017Z","steps":["trace[782071851] 'agreement among raft nodes before linearized reading' (duration: 110.40779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:37.880292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.214469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-11T01:01:37.880354Z","caller":"traceutil/trace.go:171","msg":"trace[1905587234] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15228; }","duration":"298.338301ms","start":"2026-09-11T01:01:37.581999Z","end":"2026-09-11T01:01:37.880338Z","steps":["trace[1905587234] 'agreement among raft nodes before linearized reading' (duration: 298.156947ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:01:48.620565Z","caller":"traceutil/trace.go:171","msg":"trace[561746944] linearizableReadLoop","detail":"{readStateIndex:15940; appliedIndex:15939; }","duration":"307.367153ms","start":"2026-09-11T01:01:48.313159Z","end":"2026-09-11T01:01:48.620526Z","steps":["trace[561746944] 'read index received' (duration: 307.120478ms)","trace[561746944] 'applied index is now lower than readState.Index' (duration: 245.295µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:01:48.620624Z","caller":"traceutil/trace.go:171","msg":"trace[1476257578] transaction","detail":"{read_only:false; response_revision:15402; number_of_response:1; }","duration":"307.999517ms","start":"2026-09-11T01:01:48.312582Z","end":"2026-09-11T01:01:48.620581Z","steps":["trace[1476257578] 'process raft request' (duration: 307.710491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:48.620814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:01:48.312552Z","time spent":"308.167501ms","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3711,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:01:48.621067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.746191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19894"} {"level":"info","ts":"2026-09-11T01:01:48.621212Z","caller":"traceutil/trace.go:171","msg":"trace[1173905601] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:15402; }","duration":"308.030258ms","start":"2026-09-11T01:01:48.313131Z","end":"2026-09-11T01:01:48.621161Z","steps":["trace[1173905601] 'agreement among raft nodes before linearized reading' (duration: 307.562007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:01:48.621312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:01:48.31312Z","time spent":"308.177441ms","remote":"127.0.0.1:48140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19916,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-11T01:03:16.645703Z","caller":"traceutil/trace.go:171","msg":"trace[595760784] linearizableReadLoop","detail":"{readStateIndex:17007; appliedIndex:17006; }","duration":"100.987002ms","start":"2026-09-11T01:03:16.544692Z","end":"2026-09-11T01:03:16.645679Z","steps":["trace[595760784] 'read index received' (duration: 86.808499ms)","trace[595760784] 'applied index is now lower than readState.Index' (duration: 14.177493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:03:16.645877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.176425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-424kr\" ","response":"range_response_count:1 size:20555"} {"level":"info","ts":"2026-09-11T01:03:16.645849Z","caller":"traceutil/trace.go:171","msg":"trace[749782546] transaction","detail":"{read_only:false; response_revision:16436; number_of_response:1; }","duration":"188.720799ms","start":"2026-09-11T01:03:16.457079Z","end":"2026-09-11T01:03:16.6458Z","steps":["trace[749782546] 'process raft request' (duration: 174.553048ms)","trace[749782546] 'compare' (duration: 13.577638ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:03:16.645903Z","caller":"traceutil/trace.go:171","msg":"trace[1986196159] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-424kr; range_end:; response_count:1; response_revision:16436; }","duration":"101.266437ms","start":"2026-09-11T01:03:16.544628Z","end":"2026-09-11T01:03:16.645895Z","steps":["trace[1986196159] 'agreement among raft nodes before linearized reading' (duration: 101.139714ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:04:52.65292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14561} {"level":"info","ts":"2026-09-11T01:04:52.738371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14561,"took":"82.563198ms","hash":3340196764,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-11T01:04:52.738459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3340196764,"revision":14561,"compact-revision":12529} {"level":"info","ts":"2026-09-11T01:09:10.638072Z","caller":"traceutil/trace.go:171","msg":"trace[1826700057] transaction","detail":"{read_only:false; response_revision:19037; number_of_response:1; }","duration":"235.497856ms","start":"2026-09-11T01:09:10.402543Z","end":"2026-09-11T01:09:10.638041Z","steps":["trace[1826700057] 'process raft request' (duration: 172.054872ms)","trace[1826700057] 'compare' (duration: 63.300611ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:09:31.977263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:09:31.983546Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-11T01:09:31.98374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-11T01:09:52.660812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17033} {"level":"info","ts":"2026-09-11T01:09:52.783531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17033,"took":"119.52937ms","hash":3424612605,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-11T01:09:52.783614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3424612605,"revision":17033,"compact-revision":14561} {"level":"info","ts":"2026-09-11T01:14:52.668095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19587} {"level":"info","ts":"2026-09-11T01:14:52.792741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19587,"took":"120.627171ms","hash":180036245,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":30433280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T01:14:52.792806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":180036245,"revision":19587,"compact-revision":17033} {"level":"warn","ts":"2026-09-11T01:15:03.556141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.438883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-11T01:15:03.556257Z","caller":"traceutil/trace.go:171","msg":"trace[483357964] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21796; }","duration":"184.628206ms","start":"2026-09-11T01:15:03.371603Z","end":"2026-09-11T01:15:03.556232Z","steps":["trace[483357964] 'count revisions from in-memory index tree' (duration: 184.326561ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:04.471732Z","caller":"traceutil/trace.go:171","msg":"trace[574333570] transaction","detail":"{read_only:false; response_revision:21798; number_of_response:1; }","duration":"101.144507ms","start":"2026-09-11T01:15:04.370554Z","end":"2026-09-11T01:15:04.471699Z","steps":["trace[574333570] 'process raft request' (duration: 100.875651ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:04.935385Z","caller":"traceutil/trace.go:171","msg":"trace[1804099133] transaction","detail":"{read_only:false; response_revision:21799; number_of_response:1; }","duration":"459.703722ms","start":"2026-09-11T01:15:04.475636Z","end":"2026-09-11T01:15:04.93534Z","steps":["trace[1804099133] 'process raft request' (duration: 459.529898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:04.935574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:15:04.475615Z","time spent":"459.849335ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:15:04.964421Z","caller":"traceutil/trace.go:171","msg":"trace[1599549330] transaction","detail":"{read_only:false; response_revision:21800; number_of_response:1; }","duration":"488.181979ms","start":"2026-09-11T01:15:04.476209Z","end":"2026-09-11T01:15:04.964391Z","steps":["trace[1599549330] 'process raft request' (duration: 487.984274ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:04.964664Z","caller":"traceutil/trace.go:171","msg":"trace[1331261478] linearizableReadLoop","detail":"{readStateIndex:22550; appliedIndex:22548; }","duration":"321.27918ms","start":"2026-09-11T01:15:04.64334Z","end":"2026-09-11T01:15:04.964619Z","steps":["trace[1331261478] 'read index received' (duration: 292.088998ms)","trace[1331261478] 'applied index is now lower than readState.Index' (duration: 29.186832ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:15:04.964754Z","caller":"traceutil/trace.go:171","msg":"trace[2008184765] transaction","detail":"{read_only:false; response_revision:21801; number_of_response:1; }","duration":"364.768327ms","start":"2026-09-11T01:15:04.599918Z","end":"2026-09-11T01:15:04.964687Z","steps":["trace[2008184765] 'process raft request' (duration: 364.42823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:04.96461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:15:04.47619Z","time spent":"488.304991ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:15:04.965082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.708129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-11T01:15:04.965131Z","caller":"traceutil/trace.go:171","msg":"trace[1735421442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21801; }","duration":"321.849342ms","start":"2026-09-11T01:15:04.643268Z","end":"2026-09-11T01:15:04.965117Z","steps":["trace[1735421442] 'agreement among raft nodes before linearized reading' (duration: 321.570976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:04.965142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.72259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-11T01:15:04.965134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.889409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-11T01:15:04.96517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.714044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:15:04.965242Z","caller":"traceutil/trace.go:171","msg":"trace[832763994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21801; }","duration":"304.059913ms","start":"2026-09-11T01:15:04.661159Z","end":"2026-09-11T01:15:04.965219Z","steps":["trace[832763994] 'agreement among raft nodes before linearized reading' (duration: 303.757067ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:04.96524Z","caller":"traceutil/trace.go:171","msg":"trace[1333865411] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:21801; }","duration":"238.819462ms","start":"2026-09-11T01:15:04.726393Z","end":"2026-09-11T01:15:04.965212Z","steps":["trace[1333865411] 'agreement among raft nodes before linearized reading' (duration: 238.646928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:04.965179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:15:04.643248Z","time spent":"321.921704ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-11T01:15:04.965332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:15:04.661135Z","time spent":"304.180615ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-11T01:15:04.965255Z","caller":"traceutil/trace.go:171","msg":"trace[1181000151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21801; }","duration":"279.831646ms","start":"2026-09-11T01:15:04.685401Z","end":"2026-09-11T01:15:04.965233Z","steps":["trace[1181000151] 'agreement among raft nodes before linearized reading' (duration: 279.698813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:04.96499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:15:04.59988Z","time spent":"364.973001ms","remote":"127.0.0.1:44558","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-11T01:19:52.675106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21725} {"level":"info","ts":"2026-09-11T01:19:52.792808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21725,"took":"114.01359ms","hash":1623650506,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":42422272,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-11T01:19:52.792861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623650506,"revision":21725,"compact-revision":19587} {"level":"info","ts":"2026-09-11T01:20:40.816226Z","caller":"traceutil/trace.go:171","msg":"trace[2014917992] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"126.218391ms","start":"2026-09-11T01:20:40.689966Z","end":"2026-09-11T01:20:40.816185Z","steps":["trace[2014917992] 'process raft request' (duration: 125.951406ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:24:17.425908Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c6d.wal"} {"level":"info","ts":"2026-09-11T01:24:52.68208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24684} {"level":"info","ts":"2026-09-11T01:24:52.818792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24684,"took":"133.660239ms","hash":73516419,"current-db-size-bytes":55877632,"current-db-size":"56 MB","current-db-size-in-use-bytes":33320960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-11T01:24:52.818867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":73516419,"revision":24684,"compact-revision":21725} {"level":"info","ts":"2026-09-11T01:29:12.169349Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:29:12.176211Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-11T01:29:12.176316Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}