{"level":"warn","ts":"2026-08-31T19:49:00.055985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T19:49:00.056102Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.31: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.31:2380","--initial-cluster=instance=https://162.253.55.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.31:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-31T19:49:00.056172Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T19:49:00.056189Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-08-31T19:49:00.056209Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T19:49:00.056874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"]} {"level":"info","ts":"2026-08-31T19:49:00.057004Z","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.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.31:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-31T19:49:00.061325Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.104025ms"} {"level":"info","ts":"2026-08-31T19:49:00.067781Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de05d1e64231de42","cluster-id":"4f2999739a68ff64"} {"level":"info","ts":"2026-08-31T19:49:00.067862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T19:49:00.067885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 0"} {"level":"info","ts":"2026-08-31T19:49:00.067897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de05d1e64231de42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T19:49:00.06791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 1"} {"level":"info","ts":"2026-08-31T19:49:00.068134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"warn","ts":"2026-08-31T19:49:00.07543Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T19:49:00.077608Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T19:49:00.079712Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-31T19:49:00.083107Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de05d1e64231de42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T19:49:00.084771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de05d1e64231de42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T19:49:00.084914Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T19:49:00.085079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T19:49:00.085095Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T19:49:00.086958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"info","ts":"2026-08-31T19:49:00.087484Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","added-peer-id":"de05d1e64231de42","added-peer-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-08-31T19:49:00.091003Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T19:49:00.09115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-08-31T19:49:00.091181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-08-31T19:49:00.09144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de05d1e64231de42","initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T19:49:00.091513Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T19:49:00.570862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T19:49:00.570938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T19:49:00.570994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgPreVoteResp from de05d1e64231de42 at term 1"} {"level":"info","ts":"2026-08-31T19:49:00.571015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became candidate at term 2"} {"level":"info","ts":"2026-08-31T19:49:00.571027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgVoteResp from de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-08-31T19:49:00.571043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became leader at term 2"} {"level":"info","ts":"2026-08-31T19:49:00.571063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de05d1e64231de42 elected leader de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-08-31T19:49:00.577862Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de05d1e64231de42","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.31:2379]}","request-path":"/0/members/de05d1e64231de42/attributes","cluster-id":"4f2999739a68ff64","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T19:49:00.578056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T19:49:00.578118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T19:49:00.578303Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T19:49:00.578459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T19:49:00.578321Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T19:49:00.579801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T19:49:00.580111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T19:49:00.580221Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T19:49:00.581732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T19:49:00.582289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.31:2379"} {"level":"info","ts":"2026-08-31T19:49:19.412545Z","caller":"traceutil/trace.go:171","msg":"trace[526492034] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"157.562193ms","start":"2026-08-31T19:49:19.254959Z","end":"2026-08-31T19:49:19.412521Z","steps":["trace[526492034] 'process raft request' (duration: 157.421979ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:50:12.75353Z","caller":"traceutil/trace.go:171","msg":"trace[296028028] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"196.277887ms","start":"2026-08-31T19:50:12.557212Z","end":"2026-08-31T19:50:12.75349Z","steps":["trace[296028028] 'process raft request' (duration: 194.321036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:50:13.317446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.823668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:50:13.317659Z","caller":"traceutil/trace.go:171","msg":"trace[860833915] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"101.836693ms","start":"2026-08-31T19:50:13.215803Z","end":"2026-08-31T19:50:13.317639Z","steps":["trace[860833915] 'read index received' (duration: 96.393µs)","trace[860833915] 'applied index is now lower than readState.Index' (duration: 101.73925ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:50:13.317693Z","caller":"traceutil/trace.go:171","msg":"trace[94217734] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"492.175217ms","start":"2026-08-31T19:50:12.825474Z","end":"2026-08-31T19:50:13.317649Z","steps":["trace[94217734] 'process raft request' (duration: 311.629446ms)","trace[94217734] 'compare' (duration: 179.682574ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:50:13.317886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:50:12.825377Z","time spent":"492.417464ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T19:50:13.318254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.345228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T19:50:13.318455Z","caller":"traceutil/trace.go:171","msg":"trace[1428345142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"102.583535ms","start":"2026-08-31T19:50:13.215783Z","end":"2026-08-31T19:50:13.318367Z","steps":["trace[1428345142] 'agreement among raft nodes before linearized reading' (duration: 101.982597ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:51:16.261281Z","caller":"traceutil/trace.go:171","msg":"trace[1452944559] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"152.536581ms","start":"2026-08-31T19:51:16.10868Z","end":"2026-08-31T19:51:16.261217Z","steps":["trace[1452944559] 'process raft request' (duration: 145.073535ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:51:46.360895Z","caller":"traceutil/trace.go:171","msg":"trace[162134786] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"122.691274ms","start":"2026-08-31T19:51:46.238176Z","end":"2026-08-31T19:51:46.360867Z","steps":["trace[162134786] 'process raft request' (duration: 122.506588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:35.608893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.401136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T19:52:35.609014Z","caller":"traceutil/trace.go:171","msg":"trace[1927512678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2047; }","duration":"443.601592ms","start":"2026-08-31T19:52:35.165388Z","end":"2026-08-31T19:52:35.608989Z","steps":["trace[1927512678] 'range keys from in-memory index tree' (duration: 443.096428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:35.609085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.165366Z","time spent":"443.708125ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-31T19:52:35.609309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.239967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:52:35.609543Z","caller":"traceutil/trace.go:171","msg":"trace[2144334001] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"356.668416ms","start":"2026-08-31T19:52:35.252853Z","end":"2026-08-31T19:52:35.609522Z","steps":["trace[2144334001] 'process raft request' (duration: 356.602174ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:52:35.60957Z","caller":"traceutil/trace.go:171","msg":"trace[1685757233] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"406.421945ms","start":"2026-08-31T19:52:35.203118Z","end":"2026-08-31T19:52:35.60954Z","steps":["trace[1685757233] 'process raft request' (duration: 16.645821ms)","trace[1685757233] 'compare' (duration: 389.082063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:52:35.609627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.252826Z","time spent":"356.761468ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T19:52:35.609667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.20308Z","time spent":"406.534228ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T19:52:35.672833Z","caller":"traceutil/trace.go:171","msg":"trace[2146151068] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2133; }","duration":"359.635693ms","start":"2026-08-31T19:52:35.313162Z","end":"2026-08-31T19:52:35.672798Z","steps":["trace[2146151068] 'read index received' (duration: 247.311716ms)","trace[2146151068] 'applied index is now lower than readState.Index' (duration: 112.322107ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:52:35.672899Z","caller":"traceutil/trace.go:171","msg":"trace[2073420012] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"414.019559ms","start":"2026-08-31T19:52:35.258843Z","end":"2026-08-31T19:52:35.672862Z","steps":["trace[2073420012] 'process raft request' (duration: 413.778232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:35.673072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.258774Z","time spent":"414.200655ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T19:52:35.673223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.799657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T19:52:35.673291Z","caller":"traceutil/trace.go:171","msg":"trace[294664232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2050; }","duration":"319.036314ms","start":"2026-08-31T19:52:35.354237Z","end":"2026-08-31T19:52:35.673274Z","steps":["trace[294664232] 'agreement among raft nodes before linearized reading' (duration: 318.765826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:35.673539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.354227Z","time spent":"319.226559ms","remote":"127.0.0.1:46160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-31T19:52:35.673704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.419145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T19:52:35.673907Z","caller":"traceutil/trace.go:171","msg":"trace[540361393] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2050; }","duration":"360.762197ms","start":"2026-08-31T19:52:35.313081Z","end":"2026-08-31T19:52:35.673843Z","steps":["trace[540361393] 'agreement among raft nodes before linearized reading' (duration: 359.928782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:35.674074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.313023Z","time spent":"361.034545ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-31T19:52:36.052992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.396839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:52:36.053262Z","caller":"traceutil/trace.go:171","msg":"trace[127304753] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"373.136992ms","start":"2026-08-31T19:52:35.680089Z","end":"2026-08-31T19:52:36.053226Z","steps":["trace[127304753] 'process raft request' (duration: 43.31541ms)","trace[127304753] 'compare' (duration: 328.777841ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:52:36.053443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:52:35.680069Z","time spent":"373.272046ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T19:52:51.908328Z","caller":"traceutil/trace.go:171","msg":"trace[1270207209] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"230.036855ms","start":"2026-08-31T19:52:51.678267Z","end":"2026-08-31T19:52:51.908304Z","steps":["trace[1270207209] 'process raft request' (duration: 229.860978ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:52:51.909452Z","caller":"traceutil/trace.go:171","msg":"trace[434010762] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2273; }","duration":"168.955262ms","start":"2026-08-31T19:52:51.740468Z","end":"2026-08-31T19:52:51.909423Z","steps":["trace[434010762] 'read index received' (duration: 167.669652ms)","trace[434010762] 'applied index is now lower than readState.Index' (duration: 1.28455ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:52:51.909667Z","caller":"traceutil/trace.go:171","msg":"trace[1477252253] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"229.261409ms","start":"2026-08-31T19:52:51.680352Z","end":"2026-08-31T19:52:51.909614Z","steps":["trace[1477252253] 'process raft request' (duration: 228.907829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:51.909822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.191812ms","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-08-31T19:52:51.909873Z","caller":"traceutil/trace.go:171","msg":"trace[1156411465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2185; }","duration":"129.290345ms","start":"2026-08-31T19:52:51.780572Z","end":"2026-08-31T19:52:51.909862Z","steps":["trace[1156411465] 'agreement among raft nodes before linearized reading' (duration: 129.080058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:52:51.909858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.356665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T19:52:51.909939Z","caller":"traceutil/trace.go:171","msg":"trace[1005987718] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"169.488099ms","start":"2026-08-31T19:52:51.740429Z","end":"2026-08-31T19:52:51.909917Z","steps":["trace[1005987718] 'agreement among raft nodes before linearized reading' (duration: 169.160108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:53:24.630401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.075108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:53:24.63054Z","caller":"traceutil/trace.go:171","msg":"trace[1603783830] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"190.480139ms","start":"2026-08-31T19:53:24.440034Z","end":"2026-08-31T19:53:24.630514Z","steps":["trace[1603783830] 'process raft request' (duration: 40.138684ms)","trace[1603783830] 'compare' (duration: 149.960134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:53:30.367278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.761118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T19:53:30.367408Z","caller":"traceutil/trace.go:171","msg":"trace[1999209766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2395; }","duration":"149.944883ms","start":"2026-08-31T19:53:30.217435Z","end":"2026-08-31T19:53:30.36738Z","steps":["trace[1999209766] 'range keys from in-memory index tree' (duration: 149.671415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:53:31.318683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.18843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:53:31.318916Z","caller":"traceutil/trace.go:171","msg":"trace[1307248835] linearizableReadLoop","detail":"{readStateIndex:2500; appliedIndex:2498; }","duration":"108.743308ms","start":"2026-08-31T19:53:31.210154Z","end":"2026-08-31T19:53:31.318897Z","steps":["trace[1307248835] 'read index received' (duration: 52.882µs)","trace[1307248835] 'applied index is now lower than readState.Index' (duration: 108.689416ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:53:31.319124Z","caller":"traceutil/trace.go:171","msg":"trace[188447162] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"253.045257ms","start":"2026-08-31T19:53:31.066034Z","end":"2026-08-31T19:53:31.319079Z","steps":["trace[188447162] 'process raft request' (duration: 103.102143ms)","trace[188447162] 'compare' (duration: 149.060356ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:53:31.319203Z","caller":"traceutil/trace.go:171","msg":"trace[169910644] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"252.245442ms","start":"2026-08-31T19:53:31.066915Z","end":"2026-08-31T19:53:31.319161Z","steps":["trace[169910644] 'process raft request' (duration: 251.920782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:53:31.319184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.034027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T19:53:31.319376Z","caller":"traceutil/trace.go:171","msg":"trace[1377415331] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2401; }","duration":"109.237543ms","start":"2026-08-31T19:53:31.210099Z","end":"2026-08-31T19:53:31.319337Z","steps":["trace[1377415331] 'agreement among raft nodes before linearized reading' (duration: 108.862362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:54:06.907728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.238959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-31T19:54:06.907844Z","caller":"traceutil/trace.go:171","msg":"trace[447125059] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2768; }","duration":"168.421595ms","start":"2026-08-31T19:54:06.739399Z","end":"2026-08-31T19:54:06.907821Z","steps":["trace[447125059] 'range keys from in-memory index tree' (duration: 168.056544ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:54:06.952167Z","caller":"traceutil/trace.go:171","msg":"trace[325651673] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"112.767618ms","start":"2026-08-31T19:54:06.839358Z","end":"2026-08-31T19:54:06.952125Z","steps":["trace[325651673] 'process raft request' (duration: 112.49568ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:54:39.07441Z","caller":"traceutil/trace.go:171","msg":"trace[183499734] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"151.856741ms","start":"2026-08-31T19:54:38.922531Z","end":"2026-08-31T19:54:39.074387Z","steps":["trace[183499734] 'process raft request' (duration: 151.721516ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:54:39.385941Z","caller":"traceutil/trace.go:171","msg":"trace[1029492110] linearizableReadLoop","detail":"{readStateIndex:3085; appliedIndex:3084; }","duration":"170.912911ms","start":"2026-08-31T19:54:39.214996Z","end":"2026-08-31T19:54:39.385909Z","steps":["trace[1029492110] 'read index received' (duration: 115.430699ms)","trace[1029492110] 'applied index is now lower than readState.Index' (duration: 55.480862ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:54:39.385928Z","caller":"traceutil/trace.go:171","msg":"trace[1748639729] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"265.327321ms","start":"2026-08-31T19:54:39.120545Z","end":"2026-08-31T19:54:39.385872Z","steps":["trace[1748639729] 'process raft request' (duration: 209.933551ms)","trace[1748639729] 'compare' (duration: 55.248965ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:54:39.386404Z","caller":"traceutil/trace.go:171","msg":"trace[1546152227] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"101.624248ms","start":"2026-08-31T19:54:39.284727Z","end":"2026-08-31T19:54:39.386352Z","steps":["trace[1546152227] 'process raft request' (duration: 101.143384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:54:39.386674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.630694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T19:54:39.386812Z","caller":"traceutil/trace.go:171","msg":"trace[1247730675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2967; }","duration":"171.834819ms","start":"2026-08-31T19:54:39.214926Z","end":"2026-08-31T19:54:39.386761Z","steps":["trace[1247730675] 'agreement among raft nodes before linearized reading' (duration: 171.253121ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:54:44.659514Z","caller":"traceutil/trace.go:171","msg":"trace[1203180880] transaction","detail":"{read_only:false; response_revision:2999; number_of_response:1; }","duration":"108.428556ms","start":"2026-08-31T19:54:44.551049Z","end":"2026-08-31T19:54:44.659478Z","steps":["trace[1203180880] 'process raft request' (duration: 108.240921ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:57:21.873703Z","caller":"traceutil/trace.go:171","msg":"trace[805762651] linearizableReadLoop","detail":"{readStateIndex:4122; appliedIndex:4121; }","duration":"104.279925ms","start":"2026-08-31T19:57:21.769396Z","end":"2026-08-31T19:57:21.873676Z","steps":["trace[805762651] 'read index received' (duration: 64.075232ms)","trace[805762651] 'applied index is now lower than readState.Index' (duration: 40.203353ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:57:21.873793Z","caller":"traceutil/trace.go:171","msg":"trace[1495383609] transaction","detail":"{read_only:false; response_revision:3963; number_of_response:1; }","duration":"107.967234ms","start":"2026-08-31T19:57:21.765776Z","end":"2026-08-31T19:57:21.873743Z","steps":["trace[1495383609] 'process raft request' (duration: 67.753431ms)","trace[1495383609] 'compare' (duration: 39.981397ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:57:21.874165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.729989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T19:57:21.874238Z","caller":"traceutil/trace.go:171","msg":"trace[1034301728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3963; }","duration":"104.857322ms","start":"2026-08-31T19:57:21.769365Z","end":"2026-08-31T19:57:21.874222Z","steps":["trace[1034301728] 'agreement among raft nodes before linearized reading' (duration: 104.43303ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:57:21.874425Z","caller":"traceutil/trace.go:171","msg":"trace[1008364600] transaction","detail":"{read_only:false; response_revision:3964; number_of_response:1; }","duration":"104.830121ms","start":"2026-08-31T19:57:21.769487Z","end":"2026-08-31T19:57:21.874317Z","steps":["trace[1008364600] 'process raft request' (duration: 104.699928ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:57:56.249663Z","caller":"traceutil/trace.go:171","msg":"trace[810014720] linearizableReadLoop","detail":"{readStateIndex:4370; appliedIndex:4369; }","duration":"120.418322ms","start":"2026-08-31T19:57:56.129217Z","end":"2026-08-31T19:57:56.249636Z","steps":["trace[810014720] 'read index received' (duration: 120.260937ms)","trace[810014720] 'applied index is now lower than readState.Index' (duration: 156.185µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:57:56.249771Z","caller":"traceutil/trace.go:171","msg":"trace[1633958933] transaction","detail":"{read_only:false; response_revision:4204; number_of_response:1; }","duration":"121.318829ms","start":"2026-08-31T19:57:56.128409Z","end":"2026-08-31T19:57:56.249728Z","steps":["trace[1633958933] 'process raft request' (duration: 121.01397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:57:56.250067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.824814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62967"} {"level":"info","ts":"2026-08-31T19:57:56.250193Z","caller":"traceutil/trace.go:171","msg":"trace[2057975386] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4204; }","duration":"121.01863ms","start":"2026-08-31T19:57:56.129155Z","end":"2026-08-31T19:57:56.250173Z","steps":["trace[2057975386] 'agreement among raft nodes before linearized reading' (duration: 120.640059ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:00.616137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2694} {"level":"info","ts":"2026-08-31T19:59:00.695726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2694,"took":"78.597179ms","hash":1022993095,"current-db-size-bytes":42360832,"current-db-size":"42 MB","current-db-size-in-use-bytes":18776064,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-31T19:59:00.695808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1022993095,"revision":2694,"compact-revision":-1} {"level":"warn","ts":"2026-08-31T19:59:35.761324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.953658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-08-31T19:59:35.761449Z","caller":"traceutil/trace.go:171","msg":"trace[689465559] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5462; }","duration":"321.145644ms","start":"2026-08-31T19:59:35.440277Z","end":"2026-08-31T19:59:35.761423Z","steps":["trace[689465559] 'range keys from in-memory index tree' (duration: 320.749352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:35.761498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:35.44025Z","time spent":"321.234487ms","remote":"127.0.0.1:46348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":917,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-08-31T19:59:35.761552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.461356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T19:59:35.761712Z","caller":"traceutil/trace.go:171","msg":"trace[1873654919] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"177.839001ms","start":"2026-08-31T19:59:35.58385Z","end":"2026-08-31T19:59:35.761689Z","steps":["trace[1873654919] 'process raft request' (duration: 20.146448ms)","trace[1873654919] 'compare' (duration: 157.26986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T19:59:35.761956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.871347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787117"} {"level":"info","ts":"2026-08-31T19:59:35.762129Z","caller":"traceutil/trace.go:171","msg":"trace[1530231517] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5462; }","duration":"323.118985ms","start":"2026-08-31T19:59:35.438975Z","end":"2026-08-31T19:59:35.762094Z","steps":["trace[1530231517] 'range keys from in-memory index tree' (duration: 321.98383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:35.762213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:35.438944Z","time spent":"323.247599ms","remote":"127.0.0.1:46230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":787140,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-08-31T19:59:35.779897Z","caller":"traceutil/trace.go:171","msg":"trace[1191140023] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"191.554131ms","start":"2026-08-31T19:59:35.588316Z","end":"2026-08-31T19:59:35.779871Z","steps":["trace[1191140023] 'process raft request' (duration: 191.213661ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:36.136971Z","caller":"traceutil/trace.go:171","msg":"trace[821006308] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"175.52153ms","start":"2026-08-31T19:59:35.961418Z","end":"2026-08-31T19:59:36.13694Z","steps":["trace[821006308] 'process raft request' (duration: 175.313024ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:36.168788Z","caller":"traceutil/trace.go:171","msg":"trace[1191702768] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"144.439668ms","start":"2026-08-31T19:59:36.024322Z","end":"2026-08-31T19:59:36.168762Z","steps":["trace[1191702768] 'process raft request' (duration: 144.201381ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:36.314207Z","caller":"traceutil/trace.go:171","msg":"trace[1026054011] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"105.638388ms","start":"2026-08-31T19:59:36.208537Z","end":"2026-08-31T19:59:36.314176Z","steps":["trace[1026054011] 'process raft request' (duration: 96.684694ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:51.590251Z","caller":"traceutil/trace.go:171","msg":"trace[1008626477] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"533.736211ms","start":"2026-08-31T19:59:51.056487Z","end":"2026-08-31T19:59:51.590223Z","steps":["trace[1008626477] 'process raft request' (duration: 533.479883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.590457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:51.056442Z","time spent":"533.908517ms","remote":"127.0.0.1:46304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T19:59:51.590591Z","caller":"traceutil/trace.go:171","msg":"trace[1614158214] linearizableReadLoop","detail":"{readStateIndex:5960; appliedIndex:5957; }","duration":"465.059467ms","start":"2026-08-31T19:59:51.125499Z","end":"2026-08-31T19:59:51.590559Z","steps":["trace[1614158214] 'read index received' (duration: 464.444758ms)","trace[1614158214] 'applied index is now lower than readState.Index' (duration: 613.699µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T19:59:51.590662Z","caller":"traceutil/trace.go:171","msg":"trace[626317066] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"533.538925ms","start":"2026-08-31T19:59:51.057085Z","end":"2026-08-31T19:59:51.590624Z","steps":["trace[626317066] 'process raft request' (duration: 533.352449ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:51.590734Z","caller":"traceutil/trace.go:171","msg":"trace[1721185318] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"533.952569ms","start":"2026-08-31T19:59:51.056769Z","end":"2026-08-31T19:59:51.590722Z","steps":["trace[1721185318] 'process raft request' (duration: 533.395121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.590942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:51.05707Z","time spent":"533.67836ms","remote":"127.0.0.1:46432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T19:59:51.590945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:51.056735Z","time spent":"534.031671ms","remote":"127.0.0.1:46432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T19:59:51.591145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.611434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-31T19:59:51.591293Z","caller":"traceutil/trace.go:171","msg":"trace[2085996048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5737; }","duration":"465.8181ms","start":"2026-08-31T19:59:51.125447Z","end":"2026-08-31T19:59:51.591265Z","steps":["trace[2085996048] 'agreement among raft nodes before linearized reading' (duration: 465.273744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.591389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:51.125428Z","time spent":"465.947093ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-31T19:59:51.630134Z","caller":"traceutil/trace.go:171","msg":"trace[303940673] transaction","detail":"{read_only:false; response_revision:5738; number_of_response:1; }","duration":"299.419018ms","start":"2026-08-31T19:59:51.330698Z","end":"2026-08-31T19:59:51.630117Z","steps":["trace[303940673] 'process raft request' (duration: 299.287234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.630217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.036123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T19:59:51.630282Z","caller":"traceutil/trace.go:171","msg":"trace[800881999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5738; }","duration":"415.120596ms","start":"2026-08-31T19:59:51.215146Z","end":"2026-08-31T19:59:51.630267Z","steps":["trace[800881999] 'agreement among raft nodes before linearized reading' (duration: 414.988222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.630322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T19:59:51.215137Z","time spent":"415.178448ms","remote":"127.0.0.1:46160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-31T19:59:51.630408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.909556ms","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-08-31T19:59:51.630469Z","caller":"traceutil/trace.go:171","msg":"trace[242800464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5738; }","duration":"181.034289ms","start":"2026-08-31T19:59:51.449418Z","end":"2026-08-31T19:59:51.630452Z","steps":["trace[242800464] 'agreement among raft nodes before linearized reading' (duration: 180.823903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T19:59:51.630557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.041723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-31T19:59:51.630634Z","caller":"traceutil/trace.go:171","msg":"trace[946130822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5738; }","duration":"247.197777ms","start":"2026-08-31T19:59:51.383417Z","end":"2026-08-31T19:59:51.630615Z","steps":["trace[946130822] 'agreement among raft nodes before linearized reading' (duration: 246.929609ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T19:59:51.933456Z","caller":"traceutil/trace.go:171","msg":"trace[997658517] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"104.39518ms","start":"2026-08-31T19:59:51.829034Z","end":"2026-08-31T19:59:51.933429Z","steps":["trace[997658517] 'process raft request' (duration: 104.243125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:00:06.780306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.889986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T20:00:06.780419Z","caller":"traceutil/trace.go:171","msg":"trace[92060119] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:6009; }","duration":"122.074112ms","start":"2026-08-31T20:00:06.65832Z","end":"2026-08-31T20:00:06.780394Z","steps":["trace[92060119] 'count revisions from in-memory index tree' (duration: 121.752742ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:04:00.624317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4622} {"level":"info","ts":"2026-08-31T20:04:00.686232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4622,"took":"60.049882ms","hash":1949034624,"current-db-size-bytes":42360832,"current-db-size":"42 MB","current-db-size-in-use-bytes":39366656,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-08-31T20:04:00.68632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1949034624,"revision":4622,"compact-revision":2694} {"level":"info","ts":"2026-08-31T20:06:27.623022Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023bf.wal"} {"level":"info","ts":"2026-08-31T20:06:31.50423Z","caller":"traceutil/trace.go:171","msg":"trace[150768195] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"261.622288ms","start":"2026-08-31T20:06:31.242566Z","end":"2026-08-31T20:06:31.504188Z","steps":["trace[150768195] 'process raft request' (duration: 261.332639ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:06:31.777073Z","caller":"traceutil/trace.go:171","msg":"trace[218229321] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"213.804007ms","start":"2026-08-31T20:06:31.56322Z","end":"2026-08-31T20:06:31.777024Z","steps":["trace[218229321] 'process raft request' (duration: 213.109166ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:06:31.788545Z","caller":"traceutil/trace.go:171","msg":"trace[852300469] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"223.289938ms","start":"2026-08-31T20:06:31.56521Z","end":"2026-08-31T20:06:31.7885Z","steps":["trace[852300469] 'process raft request' (duration: 223.098132ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:06:44.058726Z","caller":"traceutil/trace.go:171","msg":"trace[1661126155] linearizableReadLoop","detail":"{readStateIndex:9237; appliedIndex:9236; }","duration":"234.289372ms","start":"2026-08-31T20:06:43.824404Z","end":"2026-08-31T20:06:44.058694Z","steps":["trace[1661126155] 'read index received' (duration: 234.081096ms)","trace[1661126155] 'applied index is now lower than readState.Index' (duration: 206.776µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:06:44.05877Z","caller":"traceutil/trace.go:171","msg":"trace[170056579] transaction","detail":"{read_only:false; response_revision:8914; number_of_response:1; }","duration":"235.346185ms","start":"2026-08-31T20:06:43.823377Z","end":"2026-08-31T20:06:44.058724Z","steps":["trace[170056579] 'process raft request' (duration: 235.125079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:06:44.058997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.578213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b4cfc6775-2gznn.18d0fc9876c3cf9a\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-08-31T20:06:44.059056Z","caller":"traceutil/trace.go:171","msg":"trace[26389063] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b4cfc6775-2gznn.18d0fc9876c3cf9a; range_end:; response_count:1; response_revision:8914; }","duration":"234.718857ms","start":"2026-08-31T20:06:43.824328Z","end":"2026-08-31T20:06:44.059047Z","steps":["trace[26389063] 'agreement among raft nodes before linearized reading' (duration: 234.5176ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:06:44.123959Z","caller":"traceutil/trace.go:171","msg":"trace[684516476] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"284.385522ms","start":"2026-08-31T20:06:43.839536Z","end":"2026-08-31T20:06:44.123921Z","steps":["trace[684516476] 'process raft request' (duration: 235.036936ms)","trace[684516476] 'compare' (duration: 48.870982ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:06:44.124306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.848224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-31T20:06:44.124307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.943463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-31T20:06:44.124368Z","caller":"traceutil/trace.go:171","msg":"trace[812493353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8916; }","duration":"184.962377ms","start":"2026-08-31T20:06:43.93939Z","end":"2026-08-31T20:06:44.124352Z","steps":["trace[812493353] 'agreement among raft nodes before linearized reading' (duration: 184.789842ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:06:44.12438Z","caller":"traceutil/trace.go:171","msg":"trace[572401223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8916; }","duration":"180.059016ms","start":"2026-08-31T20:06:43.944302Z","end":"2026-08-31T20:06:44.124361Z","steps":["trace[572401223] 'agreement among raft nodes before linearized reading' (duration: 179.83108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:06:44.124306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.811717ms","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-08-31T20:06:44.124463Z","caller":"traceutil/trace.go:171","msg":"trace[41437276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8916; }","duration":"175.021854ms","start":"2026-08-31T20:06:43.949424Z","end":"2026-08-31T20:06:44.124446Z","steps":["trace[41437276] 'agreement among raft nodes before linearized reading' (duration: 174.753566ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:10.864567Z","caller":"traceutil/trace.go:171","msg":"trace[1266422097] transaction","detail":"{read_only:false; response_revision:9115; number_of_response:1; }","duration":"120.964331ms","start":"2026-08-31T20:07:10.743554Z","end":"2026-08-31T20:07:10.864519Z","steps":["trace[1266422097] 'process raft request' (duration: 120.618021ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:15.152972Z","caller":"traceutil/trace.go:171","msg":"trace[600503652] linearizableReadLoop","detail":"{readStateIndex:9471; appliedIndex:9470; }","duration":"257.882037ms","start":"2026-08-31T20:07:14.895064Z","end":"2026-08-31T20:07:15.152946Z","steps":["trace[600503652] 'read index received' (duration: 257.692361ms)","trace[600503652] 'applied index is now lower than readState.Index' (duration: 188.446µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:15.153259Z","caller":"traceutil/trace.go:171","msg":"trace[1057836283] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"278.666408ms","start":"2026-08-31T20:07:14.874544Z","end":"2026-08-31T20:07:15.15321Z","steps":["trace[1057836283] 'process raft request' (duration: 278.224485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:07:15.153341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.278058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-31T20:07:15.153412Z","caller":"traceutil/trace.go:171","msg":"trace[116871993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9138; }","duration":"258.418213ms","start":"2026-08-31T20:07:14.894971Z","end":"2026-08-31T20:07:15.153389Z","steps":["trace[116871993] 'agreement among raft nodes before linearized reading' (duration: 258.101704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:07:15.418101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.208054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T20:07:15.418229Z","caller":"traceutil/trace.go:171","msg":"trace[1308209626] linearizableReadLoop","detail":"{readStateIndex:9472; appliedIndex:9471; }","duration":"203.455435ms","start":"2026-08-31T20:07:15.214752Z","end":"2026-08-31T20:07:15.418208Z","steps":["trace[1308209626] 'read index received' (duration: 111.283µs)","trace[1308209626] 'applied index is now lower than readState.Index' (duration: 203.342342ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:15.418268Z","caller":"traceutil/trace.go:171","msg":"trace[79072349] transaction","detail":"{read_only:false; response_revision:9139; number_of_response:1; }","duration":"259.465046ms","start":"2026-08-31T20:07:15.158766Z","end":"2026-08-31T20:07:15.418231Z","steps":["trace[79072349] 'process raft request' (duration: 37.976813ms)","trace[79072349] 'compare' (duration: 220.993377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:07:15.418531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.723543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:07:15.419078Z","caller":"traceutil/trace.go:171","msg":"trace[2103004692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9139; }","duration":"204.351372ms","start":"2026-08-31T20:07:15.214702Z","end":"2026-08-31T20:07:15.419053Z","steps":["trace[2103004692] 'agreement among raft nodes before linearized reading' (duration: 203.722704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:07:15.419477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.373164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T20:07:15.419571Z","caller":"traceutil/trace.go:171","msg":"trace[691837519] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:9139; }","duration":"122.836748ms","start":"2026-08-31T20:07:15.296716Z","end":"2026-08-31T20:07:15.419552Z","steps":["trace[691837519] 'agreement among raft nodes before linearized reading' (duration: 122.358493ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:17.338003Z","caller":"traceutil/trace.go:171","msg":"trace[48165858] linearizableReadLoop","detail":"{readStateIndex:9481; appliedIndex:9480; }","duration":"121.439997ms","start":"2026-08-31T20:07:17.216537Z","end":"2026-08-31T20:07:17.337977Z","steps":["trace[48165858] 'read index received' (duration: 121.21436ms)","trace[48165858] 'applied index is now lower than readState.Index' (duration: 224.457µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:17.338117Z","caller":"traceutil/trace.go:171","msg":"trace[781755175] transaction","detail":"{read_only:false; response_revision:9148; number_of_response:1; }","duration":"178.152187ms","start":"2026-08-31T20:07:17.159915Z","end":"2026-08-31T20:07:17.338068Z","steps":["trace[781755175] 'process raft request' (duration: 177.90198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:07:17.338185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.622242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:07:17.338232Z","caller":"traceutil/trace.go:171","msg":"trace[635297453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9148; }","duration":"121.716575ms","start":"2026-08-31T20:07:17.216499Z","end":"2026-08-31T20:07:17.338216Z","steps":["trace[635297453] 'agreement among raft nodes before linearized reading' (duration: 121.628562ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:25.977837Z","caller":"traceutil/trace.go:171","msg":"trace[1315874872] linearizableReadLoop","detail":"{readStateIndex:9530; appliedIndex:9529; }","duration":"129.981884ms","start":"2026-08-31T20:07:25.847832Z","end":"2026-08-31T20:07:25.977814Z","steps":["trace[1315874872] 'read index received' (duration: 129.751877ms)","trace[1315874872] 'applied index is now lower than readState.Index' (duration: 228.247µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:25.977899Z","caller":"traceutil/trace.go:171","msg":"trace[1321750704] transaction","detail":"{read_only:false; response_revision:9195; number_of_response:1; }","duration":"149.522567ms","start":"2026-08-31T20:07:25.828352Z","end":"2026-08-31T20:07:25.977875Z","steps":["trace[1321750704] 'process raft request' (duration: 149.290081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:07:25.978123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.258974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T20:07:25.978178Z","caller":"traceutil/trace.go:171","msg":"trace[184562754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9195; }","duration":"130.384528ms","start":"2026-08-31T20:07:25.847778Z","end":"2026-08-31T20:07:25.978162Z","steps":["trace[184562754] 'agreement among raft nodes before linearized reading' (duration: 130.174791ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:26.158934Z","caller":"traceutil/trace.go:171","msg":"trace[548621338] linearizableReadLoop","detail":"{readStateIndex:9531; appliedIndex:9530; }","duration":"174.281501ms","start":"2026-08-31T20:07:25.984615Z","end":"2026-08-31T20:07:26.158897Z","steps":["trace[548621338] 'read index received' (duration: 122.155188ms)","trace[548621338] 'applied index is now lower than readState.Index' (duration: 52.124813ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:26.159106Z","caller":"traceutil/trace.go:171","msg":"trace[390326381] transaction","detail":"{read_only:false; response_revision:9196; number_of_response:1; }","duration":"175.387513ms","start":"2026-08-31T20:07:25.983662Z","end":"2026-08-31T20:07:26.15905Z","steps":["trace[390326381] 'process raft request' (duration: 123.059464ms)","trace[390326381] 'compare' (duration: 51.772452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:07:26.159652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.995022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228818"} {"level":"info","ts":"2026-08-31T20:07:26.159719Z","caller":"traceutil/trace.go:171","msg":"trace[205438611] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9196; }","duration":"175.114735ms","start":"2026-08-31T20:07:25.984589Z","end":"2026-08-31T20:07:26.159704Z","steps":["trace[205438611] 'agreement among raft nodes before linearized reading' (duration: 174.436535ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:07:26.64223Z","caller":"traceutil/trace.go:171","msg":"trace[1922842988] linearizableReadLoop","detail":"{readStateIndex:9537; appliedIndex:9535; }","duration":"117.038673ms","start":"2026-08-31T20:07:26.525151Z","end":"2026-08-31T20:07:26.64219Z","steps":["trace[1922842988] 'read index received' (duration: 74.410549ms)","trace[1922842988] 'applied index is now lower than readState.Index' (duration: 42.626324ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:07:26.642345Z","caller":"traceutil/trace.go:171","msg":"trace[1046671325] transaction","detail":"{read_only:false; response_revision:9202; number_of_response:1; }","duration":"134.017058ms","start":"2026-08-31T20:07:26.508288Z","end":"2026-08-31T20:07:26.642305Z","steps":["trace[1046671325] 'process raft request' (duration: 101.700237ms)","trace[1046671325] 'compare' (duration: 31.95331ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:07:26.642472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.259559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:07:26.642554Z","caller":"traceutil/trace.go:171","msg":"trace[2036065633] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:9202; }","duration":"117.422674ms","start":"2026-08-31T20:07:26.525112Z","end":"2026-08-31T20:07:26.642534Z","steps":["trace[2036065633] 'agreement among raft nodes before linearized reading' (duration: 117.214018ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:08:14.689368Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T20:08:14.695915Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-31T20:08:14.696064Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-31T20:08:58.966158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.279089ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:08:58.966375Z","caller":"traceutil/trace.go:171","msg":"trace[748636406] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:10070; }","duration":"122.534057ms","start":"2026-08-31T20:08:58.843811Z","end":"2026-08-31T20:08:58.966345Z","steps":["trace[748636406] 'range keys from in-memory index tree' (duration: 122.246868ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:08:58.973639Z","caller":"traceutil/trace.go:171","msg":"trace[176887892] linearizableReadLoop","detail":"{readStateIndex:10432; appliedIndex:10430; }","duration":"194.158643ms","start":"2026-08-31T20:08:58.779462Z","end":"2026-08-31T20:08:58.97362Z","steps":["trace[176887892] 'read index received' (duration: 193.896866ms)","trace[176887892] 'applied index is now lower than readState.Index' (duration: 260.857µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:08:58.973686Z","caller":"traceutil/trace.go:171","msg":"trace[1174387642] transaction","detail":"{read_only:false; response_revision:10071; number_of_response:1; }","duration":"256.584441ms","start":"2026-08-31T20:08:58.717073Z","end":"2026-08-31T20:08:58.973657Z","steps":["trace[1174387642] 'process raft request' (duration: 256.319804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:08:58.973914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.428662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T20:08:58.974036Z","caller":"traceutil/trace.go:171","msg":"trace[750881052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10072; }","duration":"194.592057ms","start":"2026-08-31T20:08:58.77941Z","end":"2026-08-31T20:08:58.974002Z","steps":["trace[750881052] 'agreement among raft nodes before linearized reading' (duration: 194.321689ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:08:58.97419Z","caller":"traceutil/trace.go:171","msg":"trace[1617042604] transaction","detail":"{read_only:false; response_revision:10072; number_of_response:1; }","duration":"254.375365ms","start":"2026-08-31T20:08:58.71931Z","end":"2026-08-31T20:08:58.973685Z","steps":["trace[1617042604] 'process raft request' (duration: 254.259322ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:09:00.631314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7659} {"level":"info","ts":"2026-08-31T20:09:00.746248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7659,"took":"112.096485ms","hash":2566068971,"current-db-size-bytes":42754048,"current-db-size":"43 MB","current-db-size-in-use-bytes":25423872,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-31T20:09:00.74634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2566068971,"revision":7659,"compact-revision":4622} {"level":"info","ts":"2026-08-31T20:14:00.63928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10083} {"level":"info","ts":"2026-08-31T20:14:00.726211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10083,"took":"84.03476ms","hash":1111162762,"current-db-size-bytes":42754048,"current-db-size":"43 MB","current-db-size-in-use-bytes":24518656,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-31T20:14:00.726295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1111162762,"revision":10083,"compact-revision":7659} {"level":"warn","ts":"2026-08-31T20:14:19.653686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.401194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-31T20:14:19.653817Z","caller":"traceutil/trace.go:171","msg":"trace[1753169881] linearizableReadLoop","detail":"{readStateIndex:12839; appliedIndex:12838; }","duration":"228.067032ms","start":"2026-08-31T20:14:19.425726Z","end":"2026-08-31T20:14:19.653793Z","steps":["trace[1753169881] 'read index received' (duration: 49.455621ms)","trace[1753169881] 'applied index is now lower than readState.Index' (duration: 178.6092ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:14:19.654035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.244726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T20:14:19.654147Z","caller":"traceutil/trace.go:171","msg":"trace[912919097] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:12402; }","duration":"228.463373ms","start":"2026-08-31T20:14:19.425659Z","end":"2026-08-31T20:14:19.654122Z","steps":["trace[912919097] 'agreement among raft nodes before linearized reading' (duration: 228.226236ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:14:39.123583Z","caller":"traceutil/trace.go:171","msg":"trace[990202661] transaction","detail":"{read_only:false; response_revision:12516; number_of_response:1; }","duration":"133.520002ms","start":"2026-08-31T20:14:38.990024Z","end":"2026-08-31T20:14:39.123544Z","steps":["trace[990202661] 'process raft request' (duration: 133.44162ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:14:39.123856Z","caller":"traceutil/trace.go:171","msg":"trace[374782588] transaction","detail":"{read_only:false; response_revision:12515; number_of_response:1; }","duration":"133.855492ms","start":"2026-08-31T20:14:38.989954Z","end":"2026-08-31T20:14:39.12381Z","steps":["trace[374782588] 'process raft request' (duration: 97.8698ms)","trace[374782588] 'compare' (duration: 35.422475ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:14:39.351171Z","caller":"traceutil/trace.go:171","msg":"trace[1467843741] transaction","detail":"{read_only:false; response_revision:12517; number_of_response:1; }","duration":"171.259468ms","start":"2026-08-31T20:14:39.17987Z","end":"2026-08-31T20:14:39.351129Z","steps":["trace[1467843741] 'process raft request' (duration: 170.876966ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:14:39.351342Z","caller":"traceutil/trace.go:171","msg":"trace[661867714] linearizableReadLoop","detail":"{readStateIndex:12957; appliedIndex:12956; }","duration":"136.151232ms","start":"2026-08-31T20:14:39.215153Z","end":"2026-08-31T20:14:39.351304Z","steps":["trace[661867714] 'read index received' (duration: 135.519792ms)","trace[661867714] 'applied index is now lower than readState.Index' (duration: 628.819µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:14:39.351525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.337348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:14:39.351572Z","caller":"traceutil/trace.go:171","msg":"trace[1517299309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12517; }","duration":"136.466461ms","start":"2026-08-31T20:14:39.215089Z","end":"2026-08-31T20:14:39.351556Z","steps":["trace[1517299309] 'agreement among raft nodes before linearized reading' (duration: 136.349888ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:14:40.599702Z","caller":"traceutil/trace.go:171","msg":"trace[1925083861] transaction","detail":"{read_only:false; response_revision:12521; number_of_response:1; }","duration":"241.227821ms","start":"2026-08-31T20:14:40.358433Z","end":"2026-08-31T20:14:40.599661Z","steps":["trace[1925083861] 'process raft request' (duration: 218.375038ms)","trace[1925083861] 'compare' (duration: 22.694738ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:14:40.622155Z","caller":"traceutil/trace.go:171","msg":"trace[276269944] transaction","detail":"{read_only:false; response_revision:12522; number_of_response:1; }","duration":"258.98613ms","start":"2026-08-31T20:14:40.363136Z","end":"2026-08-31T20:14:40.622123Z","steps":["trace[276269944] 'process raft request' (duration: 258.775203ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:19:00.647331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12111} {"level":"info","ts":"2026-08-31T20:19:00.729135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12111,"took":"78.380688ms","hash":503779205,"current-db-size-bytes":42754048,"current-db-size":"43 MB","current-db-size-in-use-bytes":27770880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T20:19:00.729222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":503779205,"revision":12111,"compact-revision":10083} {"level":"info","ts":"2026-08-31T20:21:17.651244Z","caller":"traceutil/trace.go:171","msg":"trace[1898607569] linearizableReadLoop","detail":"{readStateIndex:15690; appliedIndex:15689; }","duration":"134.968995ms","start":"2026-08-31T20:21:17.516242Z","end":"2026-08-31T20:21:17.651211Z","steps":["trace[1898607569] 'read index received' (duration: 131.779427ms)","trace[1898607569] 'applied index is now lower than readState.Index' (duration: 3.187708ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:21:17.651437Z","caller":"traceutil/trace.go:171","msg":"trace[200598113] transaction","detail":"{read_only:false; response_revision:15157; number_of_response:1; }","duration":"188.554637ms","start":"2026-08-31T20:21:17.462871Z","end":"2026-08-31T20:21:17.651425Z","steps":["trace[200598113] 'process raft request' (duration: 185.229125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:17.651564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.424366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:21:17.651672Z","caller":"traceutil/trace.go:171","msg":"trace[1126802098] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15157; }","duration":"131.612772ms","start":"2026-08-31T20:21:17.520033Z","end":"2026-08-31T20:21:17.651645Z","steps":["trace[1126802098] 'agreement among raft nodes before linearized reading' (duration: 131.451857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:17.651967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.155751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bmspv\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-31T20:21:17.652044Z","caller":"traceutil/trace.go:171","msg":"trace[976532746] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bmspv; range_end:; response_count:1; response_revision:15157; }","duration":"136.315375ms","start":"2026-08-31T20:21:17.515717Z","end":"2026-08-31T20:21:17.652032Z","steps":["trace[976532746] 'agreement among raft nodes before linearized reading' (duration: 136.080078ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:21:35.042132Z","caller":"traceutil/trace.go:171","msg":"trace[1734719641] transaction","detail":"{read_only:false; response_revision:15258; number_of_response:1; }","duration":"103.139389ms","start":"2026-08-31T20:21:34.938963Z","end":"2026-08-31T20:21:35.042102Z","steps":["trace[1734719641] 'process raft request' (duration: 102.920512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:35.858956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.406738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T20:21:35.859082Z","caller":"traceutil/trace.go:171","msg":"trace[1489345071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15262; }","duration":"119.598814ms","start":"2026-08-31T20:21:35.739456Z","end":"2026-08-31T20:21:35.859055Z","steps":["trace[1489345071] 'range keys from in-memory index tree' (duration: 119.145549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:35.859167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.623484ms","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-08-31T20:21:35.859277Z","caller":"traceutil/trace.go:171","msg":"trace[356816948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15262; }","duration":"244.793569ms","start":"2026-08-31T20:21:35.614453Z","end":"2026-08-31T20:21:35.859247Z","steps":["trace[356816948] 'range keys from in-memory index tree' (duration: 244.375017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:35.859514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.019696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:21:35.859553Z","caller":"traceutil/trace.go:171","msg":"trace[716249924] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:15262; }","duration":"214.094448ms","start":"2026-08-31T20:21:35.645445Z","end":"2026-08-31T20:21:35.85954Z","steps":["trace[716249924] 'count revisions from in-memory index tree' (duration: 213.961725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:46.607221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.504826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T20:21:46.607769Z","caller":"traceutil/trace.go:171","msg":"trace[368316864] linearizableReadLoop","detail":"{readStateIndex:15981; appliedIndex:15979; }","duration":"273.452276ms","start":"2026-08-31T20:21:46.334293Z","end":"2026-08-31T20:21:46.607746Z","steps":["trace[368316864] 'read index received' (duration: 98.315032ms)","trace[368316864] 'applied index is now lower than readState.Index' (duration: 175.135834ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:21:46.607887Z","caller":"traceutil/trace.go:171","msg":"trace[1060586325] transaction","detail":"{read_only:false; response_revision:15439; number_of_response:1; }","duration":"286.948069ms","start":"2026-08-31T20:21:46.320892Z","end":"2026-08-31T20:21:46.60784Z","steps":["trace[1060586325] 'process raft request' (duration: 286.483214ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:21:46.607975Z","caller":"traceutil/trace.go:171","msg":"trace[1173732439] transaction","detail":"{read_only:false; response_revision:15440; number_of_response:1; }","duration":"214.359986ms","start":"2026-08-31T20:21:46.393598Z","end":"2026-08-31T20:21:46.607958Z","steps":["trace[1173732439] 'process raft request' (duration: 214.099497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:46.608165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.387387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T20:21:46.608272Z","caller":"traceutil/trace.go:171","msg":"trace[64224321] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15440; }","duration":"181.561541ms","start":"2026-08-31T20:21:46.426687Z","end":"2026-08-31T20:21:46.608248Z","steps":["trace[64224321] 'agreement among raft nodes before linearized reading' (duration: 181.371206ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:21:46.607577Z","caller":"traceutil/trace.go:171","msg":"trace[785504673] transaction","detail":"{read_only:false; response_revision:15438; number_of_response:1; }","duration":"297.036189ms","start":"2026-08-31T20:21:46.310495Z","end":"2026-08-31T20:21:46.607531Z","steps":["trace[785504673] 'process raft request' (duration: 122.09457ms)","trace[785504673] 'compare' (duration: 174.269989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:21:46.608467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.139938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-lvw4q\" ","response":"range_response_count:1 size:7031"} {"level":"info","ts":"2026-08-31T20:21:46.608575Z","caller":"traceutil/trace.go:171","msg":"trace[1854419852] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-lvw4q; range_end:; response_count:1; response_revision:15440; }","duration":"274.306053ms","start":"2026-08-31T20:21:46.334245Z","end":"2026-08-31T20:21:46.608551Z","steps":["trace[1854419852] 'agreement among raft nodes before linearized reading' (duration: 274.008524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:21:46.608704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.63838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-31T20:21:46.608856Z","caller":"traceutil/trace.go:171","msg":"trace[1539148530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15440; }","duration":"112.881667ms","start":"2026-08-31T20:21:46.495945Z","end":"2026-08-31T20:21:46.608827Z","steps":["trace[1539148530] 'agreement among raft nodes before linearized reading' (duration: 112.257178ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:21:46.832318Z","caller":"traceutil/trace.go:171","msg":"trace[1698234060] transaction","detail":"{read_only:false; response_revision:15441; number_of_response:1; }","duration":"220.776453ms","start":"2026-08-31T20:21:46.6115Z","end":"2026-08-31T20:21:46.832276Z","steps":["trace[1698234060] 'process raft request' (duration: 145.825867ms)","trace[1698234060] 'compare' (duration: 74.7482ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:21:46.833918Z","caller":"traceutil/trace.go:171","msg":"trace[385544413] transaction","detail":"{read_only:false; response_revision:15443; number_of_response:1; }","duration":"214.285474ms","start":"2026-08-31T20:21:46.619602Z","end":"2026-08-31T20:21:46.833888Z","steps":["trace[385544413] 'process raft request' (duration: 214.210002ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:21:46.834151Z","caller":"traceutil/trace.go:171","msg":"trace[507881850] transaction","detail":"{read_only:false; response_revision:15442; number_of_response:1; }","duration":"220.061961ms","start":"2026-08-31T20:21:46.614057Z","end":"2026-08-31T20:21:46.834118Z","steps":["trace[507881850] 'process raft request' (duration: 219.592997ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:23:30.264113Z","caller":"traceutil/trace.go:171","msg":"trace[487473532] transaction","detail":"{read_only:false; response_revision:16542; number_of_response:1; }","duration":"251.692685ms","start":"2026-08-31T20:23:30.012389Z","end":"2026-08-31T20:23:30.264081Z","steps":["trace[487473532] 'process raft request' (duration: 251.487238ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:23:30.266093Z","caller":"traceutil/trace.go:171","msg":"trace[619553110] linearizableReadLoop","detail":"{readStateIndex:17121; appliedIndex:17120; }","duration":"224.967198ms","start":"2026-08-31T20:23:30.041091Z","end":"2026-08-31T20:23:30.266059Z","steps":["trace[619553110] 'read index received' (duration: 223.171603ms)","trace[619553110] 'applied index is now lower than readState.Index' (duration: 1.794075ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:23:30.266136Z","caller":"traceutil/trace.go:171","msg":"trace[1256173179] transaction","detail":"{read_only:false; response_revision:16543; number_of_response:1; }","duration":"253.103298ms","start":"2026-08-31T20:23:30.012998Z","end":"2026-08-31T20:23:30.266101Z","steps":["trace[1256173179] 'process raft request' (duration: 252.889722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.266336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.227616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-31T20:23:30.266408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.551584ms","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-08-31T20:23:30.266436Z","caller":"traceutil/trace.go:171","msg":"trace[1119957261] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16543; }","duration":"225.34247ms","start":"2026-08-31T20:23:30.041034Z","end":"2026-08-31T20:23:30.266376Z","steps":["trace[1119957261] 'agreement among raft nodes before linearized reading' (duration: 225.134753ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:23:30.266474Z","caller":"traceutil/trace.go:171","msg":"trace[301578580] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16543; }","duration":"188.639657ms","start":"2026-08-31T20:23:30.07782Z","end":"2026-08-31T20:23:30.266459Z","steps":["trace[301578580] 'agreement among raft nodes before linearized reading' (duration: 188.513942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.266529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.721222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tjcm9\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-08-31T20:23:30.266574Z","caller":"traceutil/trace.go:171","msg":"trace[1462283240] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tjcm9; range_end:; response_count:1; response_revision:16543; }","duration":"203.806415ms","start":"2026-08-31T20:23:30.062754Z","end":"2026-08-31T20:23:30.26656Z","steps":["trace[1462283240] 'agreement among raft nodes before linearized reading' (duration: 203.591268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.266569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.661378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-31T20:23:30.266677Z","caller":"traceutil/trace.go:171","msg":"trace[250357135] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16543; }","duration":"188.776291ms","start":"2026-08-31T20:23:30.077871Z","end":"2026-08-31T20:23:30.266648Z","steps":["trace[250357135] 'agreement among raft nodes before linearized reading' (duration: 188.499432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.266539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.803962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-31T20:23:30.266838Z","caller":"traceutil/trace.go:171","msg":"trace[1336152487] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16543; }","duration":"188.188824ms","start":"2026-08-31T20:23:30.078623Z","end":"2026-08-31T20:23:30.266812Z","steps":["trace[1336152487] 'agreement among raft nodes before linearized reading' (duration: 187.631736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.266922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.353437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tjcm9\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-08-31T20:23:30.267011Z","caller":"traceutil/trace.go:171","msg":"trace[1853184844] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tjcm9; range_end:; response_count:1; response_revision:16543; }","duration":"220.497811ms","start":"2026-08-31T20:23:30.046498Z","end":"2026-08-31T20:23:30.266996Z","steps":["trace[1853184844] 'agreement among raft nodes before linearized reading' (duration: 219.728037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.554136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.129806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T20:23:30.554257Z","caller":"traceutil/trace.go:171","msg":"trace[562895424] linearizableReadLoop","detail":"{readStateIndex:17122; appliedIndex:17121; }","duration":"121.120371ms","start":"2026-08-31T20:23:30.43312Z","end":"2026-08-31T20:23:30.55424Z","steps":["trace[562895424] 'read index received' (duration: 6.662565ms)","trace[562895424] 'applied index is now lower than readState.Index' (duration: 114.456456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:23:30.554369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.980567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T20:23:30.554397Z","caller":"traceutil/trace.go:171","msg":"trace[265681307] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16544; }","duration":"121.024248ms","start":"2026-08-31T20:23:30.433364Z","end":"2026-08-31T20:23:30.554388Z","steps":["trace[265681307] 'agreement among raft nodes before linearized reading' (duration: 120.970746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.554431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.277556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T20:23:30.554548Z","caller":"traceutil/trace.go:171","msg":"trace[407049017] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16544; }","duration":"121.483603ms","start":"2026-08-31T20:23:30.433035Z","end":"2026-08-31T20:23:30.554518Z","steps":["trace[407049017] 'agreement among raft nodes before linearized reading' (duration: 121.290007ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:23:30.554877Z","caller":"traceutil/trace.go:171","msg":"trace[1616970622] transaction","detail":"{read_only:false; response_revision:16544; number_of_response:1; }","duration":"125.202407ms","start":"2026-08-31T20:23:30.429616Z","end":"2026-08-31T20:23:30.554818Z","steps":["trace[1616970622] 'process raft request' (duration: 10.257856ms)","trace[1616970622] 'compare' (duration: 113.988132ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:23:30.554947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.526474ms","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-08-31T20:23:30.55498Z","caller":"traceutil/trace.go:171","msg":"trace[1753691162] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16544; }","duration":"120.618896ms","start":"2026-08-31T20:23:30.434351Z","end":"2026-08-31T20:23:30.55497Z","steps":["trace[1753691162] 'agreement among raft nodes before linearized reading' (duration: 120.564395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:23:30.554961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.013398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-31T20:23:30.555065Z","caller":"traceutil/trace.go:171","msg":"trace[1126535732] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16544; }","duration":"121.162613ms","start":"2026-08-31T20:23:30.433863Z","end":"2026-08-31T20:23:30.555025Z","steps":["trace[1126535732] 'agreement among raft nodes before linearized reading' (duration: 120.902064ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:24:00.653531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14280} {"level":"info","ts":"2026-08-31T20:24:00.73587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14280,"took":"79.217827ms","hash":2877348117,"current-db-size-bytes":42754048,"current-db-size":"43 MB","current-db-size-in-use-bytes":30687232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T20:24:00.735951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2877348117,"revision":14280,"compact-revision":12111} {"level":"info","ts":"2026-08-31T20:29:00.661059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16699} {"level":"info","ts":"2026-08-31T20:29:00.797041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16699,"took":"129.728497ms","hash":1761947687,"current-db-size-bytes":44863488,"current-db-size":"45 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T20:29:00.797149Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1761947687,"revision":16699,"compact-revision":14280} {"level":"info","ts":"2026-08-31T20:29:11.611058Z","caller":"traceutil/trace.go:171","msg":"trace[1958396210] transaction","detail":"{read_only:false; response_revision:18982; number_of_response:1; }","duration":"215.198784ms","start":"2026-08-31T20:29:11.395824Z","end":"2026-08-31T20:29:11.611023Z","steps":["trace[1958396210] 'process raft request' (duration: 215.006848ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:11.616832Z","caller":"traceutil/trace.go:171","msg":"trace[1736169760] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"218.528595ms","start":"2026-08-31T20:29:11.398283Z","end":"2026-08-31T20:29:11.616812Z","steps":["trace[1736169760] 'process raft request' (duration: 218.301848ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:11.616797Z","caller":"traceutil/trace.go:171","msg":"trace[478509286] linearizableReadLoop","detail":"{readStateIndex:19644; appliedIndex:19642; }","duration":"185.455645ms","start":"2026-08-31T20:29:11.431305Z","end":"2026-08-31T20:29:11.61676Z","steps":["trace[478509286] 'read index received' (duration: 179.506794ms)","trace[478509286] 'applied index is now lower than readState.Index' (duration: 5.947481ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:29:11.617034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.695283ms","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-08-31T20:29:11.61709Z","caller":"traceutil/trace.go:171","msg":"trace[696228399] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"185.799356ms","start":"2026-08-31T20:29:11.431274Z","end":"2026-08-31T20:29:11.617073Z","steps":["trace[696228399] 'agreement among raft nodes before linearized reading' (duration: 185.589099ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:12.448836Z","caller":"traceutil/trace.go:171","msg":"trace[52512170] linearizableReadLoop","detail":"{readStateIndex:19646; appliedIndex:19645; }","duration":"221.183406ms","start":"2026-08-31T20:29:12.22763Z","end":"2026-08-31T20:29:12.448813Z","steps":["trace[52512170] 'read index received' (duration: 220.97362ms)","trace[52512170] 'applied index is now lower than readState.Index' (duration: 208.396µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:29:12.449103Z","caller":"traceutil/trace.go:171","msg":"trace[1219612246] transaction","detail":"{read_only:false; response_revision:18985; number_of_response:1; }","duration":"227.041955ms","start":"2026-08-31T20:29:12.221999Z","end":"2026-08-31T20:29:12.449041Z","steps":["trace[1219612246] 'process raft request' (duration: 226.676913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:29:12.449263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.60662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-31T20:29:12.449314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.825476ms","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-08-31T20:29:12.449387Z","caller":"traceutil/trace.go:171","msg":"trace[1684761484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18985; }","duration":"221.808825ms","start":"2026-08-31T20:29:12.227554Z","end":"2026-08-31T20:29:12.449363Z","steps":["trace[1684761484] 'agreement among raft nodes before linearized reading' (duration: 221.439993ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:12.449392Z","caller":"traceutil/trace.go:171","msg":"trace[2092074186] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18985; }","duration":"103.974591ms","start":"2026-08-31T20:29:12.345392Z","end":"2026-08-31T20:29:12.449366Z","steps":["trace[2092074186] 'agreement among raft nodes before linearized reading' (duration: 103.693203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:29:12.666296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.099765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T20:29:12.666461Z","caller":"traceutil/trace.go:171","msg":"trace[655997568] transaction","detail":"{read_only:false; response_revision:18986; number_of_response:1; }","duration":"211.732347ms","start":"2026-08-31T20:29:12.454702Z","end":"2026-08-31T20:29:12.666435Z","steps":["trace[655997568] 'process raft request' (duration: 29.316493ms)","trace[655997568] 'compare' (duration: 181.894808ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:29:12.692682Z","caller":"traceutil/trace.go:171","msg":"trace[969569520] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"127.355464ms","start":"2026-08-31T20:29:12.565027Z","end":"2026-08-31T20:29:12.692383Z","steps":["trace[969569520] 'process raft request' (duration: 127.172349ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:12.700402Z","caller":"traceutil/trace.go:171","msg":"trace[1089245659] transaction","detail":"{read_only:false; response_revision:18988; number_of_response:1; }","duration":"126.834759ms","start":"2026-08-31T20:29:12.573524Z","end":"2026-08-31T20:29:12.700359Z","steps":["trace[1089245659] 'process raft request' (duration: 126.517559ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:29:41.599352Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T20:29:41.605624Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-31T20:29:41.605741Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-31T20:34:00.676779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18921} {"level":"info","ts":"2026-08-31T20:34:00.786259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18921,"took":"105.334766ms","hash":1390801720,"current-db-size-bytes":47853568,"current-db-size":"48 MB","current-db-size-in-use-bytes":33812480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-31T20:34:00.786355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1390801720,"revision":18921,"compact-revision":16699} {"level":"info","ts":"2026-08-31T20:35:58.701421Z","caller":"traceutil/trace.go:171","msg":"trace[1068151847] transaction","detail":"{read_only:false; response_revision:22085; number_of_response:1; }","duration":"270.374328ms","start":"2026-08-31T20:35:58.431014Z","end":"2026-08-31T20:35:58.701389Z","steps":["trace[1068151847] 'process raft request' (duration: 270.164032ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:35:58.70987Z","caller":"traceutil/trace.go:171","msg":"trace[548971768] linearizableReadLoop","detail":"{readStateIndex:22851; appliedIndex:22849; }","duration":"217.804831ms","start":"2026-08-31T20:35:58.492022Z","end":"2026-08-31T20:35:58.709827Z","steps":["trace[548971768] 'read index received' (duration: 209.199918ms)","trace[548971768] 'applied index is now lower than readState.Index' (duration: 8.602713ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:35:58.71004Z","caller":"traceutil/trace.go:171","msg":"trace[568005893] transaction","detail":"{read_only:false; response_revision:22086; number_of_response:1; }","duration":"258.865687ms","start":"2026-08-31T20:35:58.451118Z","end":"2026-08-31T20:35:58.709984Z","steps":["trace[568005893] 'process raft request' (duration: 258.459174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:35:58.710153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.094699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-31T20:35:58.710247Z","caller":"traceutil/trace.go:171","msg":"trace[455310347] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:22087; }","duration":"218.269965ms","start":"2026-08-31T20:35:58.491961Z","end":"2026-08-31T20:35:58.710231Z","steps":["trace[455310347] 'agreement among raft nodes before linearized reading' (duration: 218.002457ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:35:58.884341Z","caller":"traceutil/trace.go:171","msg":"trace[35214103] transaction","detail":"{read_only:false; response_revision:22088; number_of_response:1; }","duration":"140.943219ms","start":"2026-08-31T20:35:58.74335Z","end":"2026-08-31T20:35:58.884293Z","steps":["trace[35214103] 'process raft request' (duration: 133.624686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:35:59.159145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.457146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T20:35:59.159313Z","caller":"traceutil/trace.go:171","msg":"trace[1071316698] transaction","detail":"{read_only:false; response_revision:22089; number_of_response:1; }","duration":"181.095147ms","start":"2026-08-31T20:35:58.978186Z","end":"2026-08-31T20:35:59.159281Z","steps":["trace[1071316698] 'process raft request' (duration: 71.879007ms)","trace[1071316698] 'compare' (duration: 107.188457ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:35:59.421287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.520635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T20:35:59.421662Z","caller":"traceutil/trace.go:171","msg":"trace[1895480078] linearizableReadLoop","detail":"{readStateIndex:22856; appliedIndex:22854; }","duration":"203.882604ms","start":"2026-08-31T20:35:59.217751Z","end":"2026-08-31T20:35:59.421633Z","steps":["trace[1895480078] 'read index received' (duration: 65.795142ms)","trace[1895480078] 'applied index is now lower than readState.Index' (duration: 138.086082ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:35:59.421703Z","caller":"traceutil/trace.go:171","msg":"trace[361095086] transaction","detail":"{read_only:false; response_revision:22090; number_of_response:1; }","duration":"442.988426ms","start":"2026-08-31T20:35:58.978669Z","end":"2026-08-31T20:35:59.421658Z","steps":["trace[361095086] 'process raft request' (duration: 304.910884ms)","trace[361095086] 'compare' (duration: 137.213906ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:35:59.421824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.064659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T20:35:59.42188Z","caller":"traceutil/trace.go:171","msg":"trace[400805228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22091; }","duration":"204.173653ms","start":"2026-08-31T20:35:59.21769Z","end":"2026-08-31T20:35:59.421864Z","steps":["trace[400805228] 'agreement among raft nodes before linearized reading' (duration: 204.032879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:35:59.421904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T20:35:58.978657Z","time spent":"443.13623ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T20:35:59.422308Z","caller":"traceutil/trace.go:171","msg":"trace[532923325] transaction","detail":"{read_only:false; response_revision:22091; number_of_response:1; }","duration":"442.807412ms","start":"2026-08-31T20:35:58.979483Z","end":"2026-08-31T20:35:59.422291Z","steps":["trace[532923325] 'process raft request' (duration: 441.974096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:35:59.4224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T20:35:58.979455Z","time spent":"442.900335ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T20:35:59.889544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.230576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-31T20:35:59.889681Z","caller":"traceutil/trace.go:171","msg":"trace[1227105770] linearizableReadLoop","detail":"{readStateIndex:22857; appliedIndex:22856; }","duration":"209.347461ms","start":"2026-08-31T20:35:59.680309Z","end":"2026-08-31T20:35:59.889657Z","steps":["trace[1227105770] 'read index received' (duration: 38.061984ms)","trace[1227105770] 'applied index is now lower than readState.Index' (duration: 171.283357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T20:35:59.889898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.581539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T20:35:59.889935Z","caller":"traceutil/trace.go:171","msg":"trace[1003054461] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22091; }","duration":"209.705932ms","start":"2026-08-31T20:35:59.680218Z","end":"2026-08-31T20:35:59.889924Z","steps":["trace[1003054461] 'agreement among raft nodes before linearized reading' (duration: 209.497526ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:35:59.937801Z","caller":"traceutil/trace.go:171","msg":"trace[535997734] transaction","detail":"{read_only:false; response_revision:22092; number_of_response:1; }","duration":"160.987183ms","start":"2026-08-31T20:35:59.776764Z","end":"2026-08-31T20:35:59.937752Z","steps":["trace[535997734] 'process raft request' (duration: 160.750756ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:36:00.166995Z","caller":"traceutil/trace.go:171","msg":"trace[1499350377] transaction","detail":"{read_only:false; response_revision:22093; number_of_response:1; }","duration":"110.317613ms","start":"2026-08-31T20:36:00.056654Z","end":"2026-08-31T20:36:00.166972Z","steps":["trace[1499350377] 'process raft request' (duration: 110.068845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:36:00.167244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.433066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T20:36:00.167301Z","caller":"traceutil/trace.go:171","msg":"trace[2056377942] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22093; }","duration":"108.57488ms","start":"2026-08-31T20:36:00.058714Z","end":"2026-08-31T20:36:00.167289Z","steps":["trace[2056377942] 'agreement among raft nodes before linearized reading' (duration: 108.353133ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:36:00.166952Z","caller":"traceutil/trace.go:171","msg":"trace[1213180161] linearizableReadLoop","detail":"{readStateIndex:22859; appliedIndex:22858; }","duration":"108.117747ms","start":"2026-08-31T20:36:00.058807Z","end":"2026-08-31T20:36:00.166925Z","steps":["trace[1213180161] 'read index received' (duration: 107.829217ms)","trace[1213180161] 'applied index is now lower than readState.Index' (duration: 287.41µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:39:00.684523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21235} {"level":"info","ts":"2026-08-31T20:39:00.805715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21235,"took":"116.4233ms","hash":2491212157,"current-db-size-bytes":48697344,"current-db-size":"49 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T20:39:00.805795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2491212157,"revision":21235,"compact-revision":18921} {"level":"info","ts":"2026-08-31T20:40:25.035565Z","caller":"traceutil/trace.go:171","msg":"trace[827990042] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"150.032317ms","start":"2026-08-31T20:40:24.885491Z","end":"2026-08-31T20:40:25.035523Z","steps":["trace[827990042] 'process raft request' (duration: 134.365915ms)","trace[827990042] 'compare' (duration: 15.26364ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:40:25.417726Z","caller":"traceutil/trace.go:171","msg":"trace[169722163] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"357.535432ms","start":"2026-08-31T20:40:25.060143Z","end":"2026-08-31T20:40:25.417678Z","steps":["trace[169722163] 'process raft request' (duration: 357.390807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:40:25.417884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T20:40:25.060118Z","time spent":"357.680135ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T20:40:25.419088Z","caller":"traceutil/trace.go:171","msg":"trace[819814628] linearizableReadLoop","detail":"{readStateIndex:25540; appliedIndex:25537; }","duration":"203.697557ms","start":"2026-08-31T20:40:25.215365Z","end":"2026-08-31T20:40:25.419062Z","steps":["trace[819814628] 'read index received' (duration: 202.712457ms)","trace[819814628] 'applied index is now lower than readState.Index' (duration: 984.07µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T20:40:25.419115Z","caller":"traceutil/trace.go:171","msg":"trace[2004977722] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"357.714356ms","start":"2026-08-31T20:40:25.061377Z","end":"2026-08-31T20:40:25.419092Z","steps":["trace[2004977722] 'process raft request' (duration: 357.391017ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:40:25.41915Z","caller":"traceutil/trace.go:171","msg":"trace[1390825028] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"285.924318ms","start":"2026-08-31T20:40:25.133159Z","end":"2026-08-31T20:40:25.419083Z","steps":["trace[1390825028] 'process raft request' (duration: 285.802944ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:40:25.419258Z","caller":"traceutil/trace.go:171","msg":"trace[1232007173] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"244.008047ms","start":"2026-08-31T20:40:25.175187Z","end":"2026-08-31T20:40:25.419195Z","steps":["trace[1232007173] 'process raft request' (duration: 243.828582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T20:40:25.419303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T20:40:25.06136Z","time spent":"357.898662ms","remote":"127.0.0.1:46422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T20:40:25.419303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.911584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T20:40:25.419492Z","caller":"traceutil/trace.go:171","msg":"trace[245152544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24696; }","duration":"204.172102ms","start":"2026-08-31T20:40:25.215304Z","end":"2026-08-31T20:40:25.419476Z","steps":["trace[245152544] 'agreement among raft nodes before linearized reading' (duration: 203.895533ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T20:43:00.511906Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006987.wal"} {"level":"info","ts":"2026-08-31T20:44:00.694917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23433} {"level":"info","ts":"2026-08-31T20:44:00.827272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23433,"took":"126.290597ms","hash":1132071909,"current-db-size-bytes":51130368,"current-db-size":"51 MB","current-db-size-in-use-bytes":42999808,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-31T20:44:00.827373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132071909,"revision":23433,"compact-revision":21235} {"level":"info","ts":"2026-08-31T20:49:00.703318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26575} {"level":"info","ts":"2026-08-31T20:49:00.875241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26575,"took":"165.757141ms","hash":1170948462,"current-db-size-bytes":51658752,"current-db-size":"52 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T20:49:00.875341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170948462,"revision":26575,"compact-revision":23433} {"level":"info","ts":"2026-08-31T20:49:32.663546Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T20:49:32.67253Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-31T20:49:32.672701Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}