{"level":"warn","ts":"2026-08-20T01:18:36.479253Z","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-20T01:18:36.479384Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-20T01:18:36.47948Z","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-20T01:18:36.479501Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-20T01:18:36.479539Z","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-20T01:18:36.480784Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-08-20T01:18:36.480926Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-20T01:18:36.483352Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.077881ms"} {"level":"info","ts":"2026-08-20T01:18:36.487601Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-08-20T01:18:36.487675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:18:36.487698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-08-20T01:18:36.487712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:18:36.487724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-08-20T01:18:36.487761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-08-20T01:18:36.492209Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:18:36.493573Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:18:36.494941Z","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-20T01:18:36.497405Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:18:36.497618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:18:36.497837Z","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-20T01:18:36.497959Z","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-20T01:18:36.498031Z","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-20T01:18:36.498468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-08-20T01:18:36.49858Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-20T01:18:36.504747Z","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-20T01:18:36.504892Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-20T01:18:36.504953Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-20T01:18:36.505222Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:18:36.505305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:18:37.388595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:18:37.388659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:18:37.388702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-08-20T01:18:37.388715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:18:37.388722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-20T01:18:37.388734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-08-20T01:18:37.388743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-20T01:18:37.389826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:18:37.391396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:18:37.391513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:18:37.39139Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:18:37.391656Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:18:37.391701Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:18:37.39206Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:18:37.392184Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:18:37.392212Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:18:37.394509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-08-20T01:18:37.395091Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:19:16.232793Z","caller":"traceutil/trace.go:171","msg":"trace[1891676215] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.75084ms","start":"2026-08-20T01:19:16.124019Z","end":"2026-08-20T01:19:16.23277Z","steps":["trace[1891676215] 'process raft request' (duration: 108.690919ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:16.232943Z","caller":"traceutil/trace.go:171","msg":"trace[1610957433] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"109.829116ms","start":"2026-08-20T01:19:16.123102Z","end":"2026-08-20T01:19:16.232931Z","steps":["trace[1610957433] 'process raft request' (duration: 92.58117ms)","trace[1610957433] 'compare' (duration: 16.857937ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:22.609259Z","caller":"traceutil/trace.go:171","msg":"trace[204970907] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"286.0912ms","start":"2026-08-20T01:19:22.323149Z","end":"2026-08-20T01:19:22.609241Z","steps":["trace[204970907] 'read index received' (duration: 285.886665ms)","trace[204970907] 'applied index is now lower than readState.Index' (duration: 204.005µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:22.609319Z","caller":"traceutil/trace.go:171","msg":"trace[1136562840] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"318.532654ms","start":"2026-08-20T01:19:22.290754Z","end":"2026-08-20T01:19:22.609287Z","steps":["trace[1136562840] 'process raft request' (duration: 318.344389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:22.609384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.232112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:19:22.609463Z","caller":"traceutil/trace.go:171","msg":"trace[1313673980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"286.375067ms","start":"2026-08-20T01:19:22.32308Z","end":"2026-08-20T01:19:22.609455Z","steps":["trace[1313673980] 'agreement among raft nodes before linearized reading' (duration: 286.260474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:22.609888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:19:22.290722Z","time spent":"318.648856ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:19:23.103667Z","caller":"traceutil/trace.go:171","msg":"trace[256605933] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"375.933592ms","start":"2026-08-20T01:19:22.727713Z","end":"2026-08-20T01:19:23.103646Z","steps":["trace[256605933] 'process raft request' (duration: 375.248256ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:23.103746Z","caller":"traceutil/trace.go:171","msg":"trace[693774376] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"103.153695ms","start":"2026-08-20T01:19:23.000574Z","end":"2026-08-20T01:19:23.103727Z","steps":["trace[693774376] 'read index received' (duration: 102.12134ms)","trace[693774376] 'applied index is now lower than readState.Index' (duration: 1.029285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:19:23.103783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:19:22.727688Z","time spent":"376.031874ms","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:19:23.103829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.255297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:19:23.103869Z","caller":"traceutil/trace.go:171","msg":"trace[1131160947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"103.324239ms","start":"2026-08-20T01:19:23.000533Z","end":"2026-08-20T01:19:23.103857Z","steps":["trace[1131160947] 'agreement among raft nodes before linearized reading' (duration: 103.253938ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:20:04.211603Z","caller":"traceutil/trace.go:171","msg":"trace[1297237767] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"120.118489ms","start":"2026-08-20T01:20:04.091463Z","end":"2026-08-20T01:20:04.211582Z","steps":["trace[1297237767] 'process raft request' (duration: 119.988416ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:20:49.780595Z","caller":"traceutil/trace.go:171","msg":"trace[78154911] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"102.418395ms","start":"2026-08-20T01:20:49.678129Z","end":"2026-08-20T01:20:49.780548Z","steps":["trace[78154911] 'process raft request' (duration: 48.599513ms)","trace[78154911] 'compare' (duration: 53.533615ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:20:50.151203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.681776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:20:50.151515Z","caller":"traceutil/trace.go:171","msg":"trace[850439387] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"364.364045ms","start":"2026-08-20T01:20:49.787125Z","end":"2026-08-20T01:20:50.151489Z","steps":["trace[850439387] 'process raft request' (duration: 117.083365ms)","trace[850439387] 'compare' (duration: 246.548733ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:20:50.151572Z","caller":"traceutil/trace.go:171","msg":"trace[2112970668] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"363.245198ms","start":"2026-08-20T01:20:49.788303Z","end":"2026-08-20T01:20:50.151548Z","steps":["trace[2112970668] 'process raft request' (duration: 363.047113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.151642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.787102Z","time spent":"364.477527ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:20:50.151674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.788275Z","time spent":"363.347101ms","remote":"127.0.0.1:51118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:20:50.151863Z","caller":"traceutil/trace.go:171","msg":"trace[1328501247] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"363.441502ms","start":"2026-08-20T01:20:49.788411Z","end":"2026-08-20T01:20:50.151853Z","steps":["trace[1328501247] 'process raft request' (duration: 363.062323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.151919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.788375Z","time spent":"363.517894ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:20:50.151982Z","caller":"traceutil/trace.go:171","msg":"trace[2016527634] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"363.444463ms","start":"2026-08-20T01:20:49.788515Z","end":"2026-08-20T01:20:50.151959Z","steps":["trace[2016527634] 'process raft request' (duration: 363.225987ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:20:50.152068Z","caller":"traceutil/trace.go:171","msg":"trace[1649566412] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"360.224162ms","start":"2026-08-20T01:20:49.791836Z","end":"2026-08-20T01:20:50.152061Z","steps":["trace[1649566412] 'process raft request' (duration: 360.011087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.152114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.791805Z","time spent":"360.279324ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:20:50.152118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.788492Z","time spent":"363.557865ms","remote":"127.0.0.1:51118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:20:50.152142Z","caller":"traceutil/trace.go:171","msg":"trace[480009755] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1922; }","duration":"289.637099ms","start":"2026-08-20T01:20:49.862479Z","end":"2026-08-20T01:20:50.152116Z","steps":["trace[480009755] 'read index received' (duration: 41.743924ms)","trace[480009755] 'applied index is now lower than readState.Index' (duration: 247.891835ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:20:50.15216Z","caller":"traceutil/trace.go:171","msg":"trace[1043011828] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"307.430156ms","start":"2026-08-20T01:20:49.844711Z","end":"2026-08-20T01:20:50.152141Z","steps":["trace[1043011828] 'process raft request' (duration: 307.243102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.152262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:20:49.844678Z","time spent":"307.534089ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:20:50.152339Z","caller":"traceutil/trace.go:171","msg":"trace[1622271880] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"142.158379ms","start":"2026-08-20T01:20:50.010171Z","end":"2026-08-20T01:20:50.15233Z","steps":["trace[1622271880] 'process raft request' (duration: 141.857501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.152462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.985729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-20T01:20:50.152487Z","caller":"traceutil/trace.go:171","msg":"trace[1430970334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"290.05505ms","start":"2026-08-20T01:20:49.862426Z","end":"2026-08-20T01:20:50.152481Z","steps":["trace[1430970334] 'agreement among raft nodes before linearized reading' (duration: 289.830695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:20:50.152484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.60206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-20T01:20:50.152496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.51877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:20:50.152538Z","caller":"traceutil/trace.go:171","msg":"trace[471023693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"287.668611ms","start":"2026-08-20T01:20:49.864853Z","end":"2026-08-20T01:20:50.152521Z","steps":["trace[471023693] 'agreement among raft nodes before linearized reading' (duration: 287.475487ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:20:50.152566Z","caller":"traceutil/trace.go:171","msg":"trace[871716937] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1856; }","duration":"250.648413ms","start":"2026-08-20T01:20:49.901903Z","end":"2026-08-20T01:20:50.152551Z","steps":["trace[871716937] 'agreement among raft nodes before linearized reading' (duration: 250.549771ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:21:34.210853Z","caller":"traceutil/trace.go:171","msg":"trace[685811853] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2225; }","duration":"358.321067ms","start":"2026-08-20T01:21:33.8525Z","end":"2026-08-20T01:21:34.210821Z","steps":["trace[685811853] 'read index received' (duration: 357.801594ms)","trace[685811853] 'applied index is now lower than readState.Index' (duration: 518.032µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:34.211034Z","caller":"traceutil/trace.go:171","msg":"trace[793518032] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"366.364574ms","start":"2026-08-20T01:21:33.844597Z","end":"2026-08-20T01:21:34.210962Z","steps":["trace[793518032] 'process raft request' (duration: 365.953264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:34.211149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.620604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-20T01:21:34.211219Z","caller":"traceutil/trace.go:171","msg":"trace[1935728350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"358.760377ms","start":"2026-08-20T01:21:33.852442Z","end":"2026-08-20T01:21:34.211202Z","steps":["trace[1935728350] 'agreement among raft nodes before linearized reading' (duration: 358.514341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:34.211256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:21:33.84457Z","time spent":"366.58241ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:21:34.211275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:21:33.852422Z","time spent":"358.842369ms","remote":"127.0.0.1:60920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-20T01:21:39.39494Z","caller":"traceutil/trace.go:171","msg":"trace[1520320271] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"104.849404ms","start":"2026-08-20T01:21:39.290057Z","end":"2026-08-20T01:21:39.394906Z","steps":["trace[1520320271] 'process raft request' (duration: 80.761893ms)","trace[1520320271] 'compare' (duration: 23.904756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:29.384215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.001266ms","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-20T01:22:29.384305Z","caller":"traceutil/trace.go:171","msg":"trace[1809463252] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2614; }","duration":"177.15705ms","start":"2026-08-20T01:22:29.207129Z","end":"2026-08-20T01:22:29.384286Z","steps":["trace[1809463252] 'range keys from in-memory index tree' (duration: 176.835102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:03.009575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.972392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:23:03.00981Z","caller":"traceutil/trace.go:171","msg":"trace[1413674027] linearizableReadLoop","detail":"{readStateIndex:2928; appliedIndex:2926; }","duration":"200.072406ms","start":"2026-08-20T01:23:02.809722Z","end":"2026-08-20T01:23:03.009794Z","steps":["trace[1413674027] 'read index received' (duration: 24.588941ms)","trace[1413674027] 'applied index is now lower than readState.Index' (duration: 175.482625ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:03.00985Z","caller":"traceutil/trace.go:171","msg":"trace[1249160016] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"202.548835ms","start":"2026-08-20T01:23:02.807263Z","end":"2026-08-20T01:23:03.009812Z","steps":["trace[1249160016] 'process raft request' (duration: 27.309907ms)","trace[1249160016] 'compare' (duration: 174.793117ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:03.009885Z","caller":"traceutil/trace.go:171","msg":"trace[1972701677] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"201.632104ms","start":"2026-08-20T01:23:02.80823Z","end":"2026-08-20T01:23:03.009862Z","steps":["trace[1972701677] 'process raft request' (duration: 201.4658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:03.009921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.496123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:23:03.009951Z","caller":"traceutil/trace.go:171","msg":"trace[383307673] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2818; }","duration":"199.551284ms","start":"2026-08-20T01:23:02.81039Z","end":"2026-08-20T01:23:03.009941Z","steps":["trace[383307673] 'agreement among raft nodes before linearized reading' (duration: 199.495462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:03.010093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.348583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-20T01:23:03.010195Z","caller":"traceutil/trace.go:171","msg":"trace[1263748482] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2818; }","duration":"200.469845ms","start":"2026-08-20T01:23:02.809693Z","end":"2026-08-20T01:23:03.010163Z","steps":["trace[1263748482] 'agreement among raft nodes before linearized reading' (duration: 200.185788ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:03.162584Z","caller":"traceutil/trace.go:171","msg":"trace[800231060] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"147.530424ms","start":"2026-08-20T01:23:03.014996Z","end":"2026-08-20T01:23:03.162526Z","steps":["trace[800231060] 'process raft request' (duration: 122.608763ms)","trace[800231060] 'compare' (duration: 24.695265ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:27:04.650717Z","caller":"traceutil/trace.go:171","msg":"trace[140283207] linearizableReadLoop","detail":"{readStateIndex:5224; appliedIndex:5223; }","duration":"263.725692ms","start":"2026-08-20T01:27:04.386963Z","end":"2026-08-20T01:27:04.650689Z","steps":["trace[140283207] 'read index received' (duration: 263.588829ms)","trace[140283207] 'applied index is now lower than readState.Index' (duration: 135.663µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:27:04.650821Z","caller":"traceutil/trace.go:171","msg":"trace[478060607] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"284.423217ms","start":"2026-08-20T01:27:04.366361Z","end":"2026-08-20T01:27:04.650784Z","steps":["trace[478060607] 'process raft request' (duration: 284.171991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:04.651215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.225034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786904"} {"level":"info","ts":"2026-08-20T01:27:04.651254Z","caller":"traceutil/trace.go:171","msg":"trace[376415972] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5033; }","duration":"264.286136ms","start":"2026-08-20T01:27:04.386958Z","end":"2026-08-20T01:27:04.651245Z","steps":["trace[376415972] 'agreement among raft nodes before linearized reading' (duration: 263.857155ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:04.673293Z","caller":"traceutil/trace.go:171","msg":"trace[185652939] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"238.228222ms","start":"2026-08-20T01:27:04.435022Z","end":"2026-08-20T01:27:04.67325Z","steps":["trace[185652939] 'process raft request' (duration: 237.893994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:04.673315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.33162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cd63998-2378-44da-89c5-199cd6811c16\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-20T01:27:04.67358Z","caller":"traceutil/trace.go:171","msg":"trace[694462829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1cd63998-2378-44da-89c5-199cd6811c16; range_end:; response_count:1; response_revision:5034; }","duration":"189.671459ms","start":"2026-08-20T01:27:04.483895Z","end":"2026-08-20T01:27:04.673566Z","steps":["trace[694462829] 'agreement among raft nodes before linearized reading' (duration: 189.208358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:04.673836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.244148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cd63998-2378-44da-89c5-199cd6811c16\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-20T01:27:04.67387Z","caller":"traceutil/trace.go:171","msg":"trace[1379234998] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1cd63998-2378-44da-89c5-199cd6811c16; range_end:; response_count:1; response_revision:5034; }","duration":"159.33644ms","start":"2026-08-20T01:27:04.514525Z","end":"2026-08-20T01:27:04.673861Z","steps":["trace[1379234998] 'agreement among raft nodes before linearized reading' (duration: 159.154246ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:05.008045Z","caller":"traceutil/trace.go:171","msg":"trace[478225571] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"125.131458ms","start":"2026-08-20T01:27:04.882886Z","end":"2026-08-20T01:27:05.008017Z","steps":["trace[478225571] 'process raft request' (duration: 124.964864ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:17.779729Z","caller":"traceutil/trace.go:171","msg":"trace[1616505898] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"140.521431ms","start":"2026-08-20T01:27:17.639185Z","end":"2026-08-20T01:27:17.779707Z","steps":["trace[1616505898] 'process raft request' (duration: 140.375217ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:18.14025Z","caller":"traceutil/trace.go:171","msg":"trace[527755275] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5473; }","duration":"410.850738ms","start":"2026-08-20T01:27:17.729353Z","end":"2026-08-20T01:27:18.140203Z","steps":["trace[527755275] 'read index received' (duration: 50.26973ms)","trace[527755275] 'applied index is now lower than readState.Index' (duration: 360.579128ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:27:18.140343Z","caller":"traceutil/trace.go:171","msg":"trace[1478134258] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"495.78403ms","start":"2026-08-20T01:27:17.644509Z","end":"2026-08-20T01:27:18.140293Z","steps":["trace[1478134258] 'process raft request' (duration: 423.479797ms)","trace[1478134258] 'compare' (duration: 71.940324ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:27:18.140747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.761343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-20T01:27:18.140575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:27:17.644489Z","time spent":"495.988625ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:27:18.140865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.725612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:27:18.140867Z","caller":"traceutil/trace.go:171","msg":"trace[2017290692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5275; }","duration":"179.937387ms","start":"2026-08-20T01:27:17.9609Z","end":"2026-08-20T01:27:18.140838Z","steps":["trace[2017290692] 'agreement among raft nodes before linearized reading' (duration: 179.620339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:18.140873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.165698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xpgsk\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-20T01:27:18.140964Z","caller":"traceutil/trace.go:171","msg":"trace[250945287] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-xpgsk; range_end:; response_count:1; response_revision:5275; }","duration":"409.285561ms","start":"2026-08-20T01:27:17.731656Z","end":"2026-08-20T01:27:18.140941Z","steps":["trace[250945287] 'agreement among raft nodes before linearized reading' (duration: 408.829209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:18.140939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.236247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xr9nl.18cd5f1e8ddecbf2\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-08-20T01:27:18.140915Z","caller":"traceutil/trace.go:171","msg":"trace[897889055] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5275; }","duration":"191.819805ms","start":"2026-08-20T01:27:17.949081Z","end":"2026-08-20T01:27:18.140901Z","steps":["trace[897889055] 'agreement among raft nodes before linearized reading' (duration: 191.708962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:18.141014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:27:17.731648Z","time spent":"409.355732ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7840,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xpgsk\" "} {"level":"info","ts":"2026-08-20T01:27:18.141533Z","caller":"traceutil/trace.go:171","msg":"trace[1384794839] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xr9nl.18cd5f1e8ddecbf2; range_end:; response_count:1; response_revision:5275; }","duration":"411.712809ms","start":"2026-08-20T01:27:17.729296Z","end":"2026-08-20T01:27:18.141009Z","steps":["trace[1384794839] 'agreement among raft nodes before linearized reading' (duration: 411.120794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:18.141687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:27:17.729274Z","time spent":"412.399396ms","remote":"127.0.0.1:50908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":854,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xr9nl.18cd5f1e8ddecbf2\" "} {"level":"info","ts":"2026-08-20T01:27:26.35664Z","caller":"traceutil/trace.go:171","msg":"trace[1008208377] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5673; }","duration":"102.666561ms","start":"2026-08-20T01:27:26.253944Z","end":"2026-08-20T01:27:26.356611Z","steps":["trace[1008208377] 'read index received' (duration: 102.444475ms)","trace[1008208377] 'applied index is now lower than readState.Index' (duration: 220.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:27:26.356703Z","caller":"traceutil/trace.go:171","msg":"trace[365973801] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"105.671363ms","start":"2026-08-20T01:27:26.251003Z","end":"2026-08-20T01:27:26.356674Z","steps":["trace[365973801] 'process raft request' (duration: 105.446008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:26.356946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.982428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-20T01:27:26.357003Z","caller":"traceutil/trace.go:171","msg":"trace[37900961] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5469; }","duration":"103.100001ms","start":"2026-08-20T01:27:26.253886Z","end":"2026-08-20T01:27:26.356986Z","steps":["trace[37900961] 'agreement among raft nodes before linearized reading' (duration: 102.896946ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:38.899407Z","caller":"traceutil/trace.go:171","msg":"trace[1524191194] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"113.240998ms","start":"2026-08-20T01:27:38.78613Z","end":"2026-08-20T01:27:38.899371Z","steps":["trace[1524191194] 'process raft request' (duration: 112.941171ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:28:37.424927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-08-20T01:28:37.521257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"94.36253ms","hash":2522442696,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T01:28:37.521341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2522442696,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-08-20T01:33:37.431429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6084} {"level":"info","ts":"2026-08-20T01:33:37.523238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6084,"took":"89.924773ms","hash":3728494969,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":23896064,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-20T01:33:37.523322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3728494969,"revision":6084,"compact-revision":3006} {"level":"warn","ts":"2026-08-20T01:33:40.616764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.367777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T01:33:40.616877Z","caller":"traceutil/trace.go:171","msg":"trace[128673875] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:8318; }","duration":"191.50905ms","start":"2026-08-20T01:33:40.425341Z","end":"2026-08-20T01:33:40.61685Z","steps":["trace[128673875] 'range keys from in-memory index tree' (duration: 191.196103ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:33:41.447091Z","caller":"traceutil/trace.go:171","msg":"trace[849149587] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"209.03704ms","start":"2026-08-20T01:33:41.238021Z","end":"2026-08-20T01:33:41.447058Z","steps":["trace[849149587] 'process raft request' (duration: 208.840015ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:33:42.444838Z","caller":"traceutil/trace.go:171","msg":"trace[348285803] linearizableReadLoop","detail":"{readStateIndex:8627; appliedIndex:8625; }","duration":"122.02952ms","start":"2026-08-20T01:33:42.322781Z","end":"2026-08-20T01:33:42.444811Z","steps":["trace[348285803] 'read index received' (duration: 3.426544ms)","trace[348285803] 'applied index is now lower than readState.Index' (duration: 118.602226ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:33:42.444933Z","caller":"traceutil/trace.go:171","msg":"trace[117708389] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"144.07707ms","start":"2026-08-20T01:33:42.300833Z","end":"2026-08-20T01:33:42.44491Z","steps":["trace[117708389] 'process raft request' (duration: 143.818703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:33:42.444984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.182463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:33:42.445007Z","caller":"traceutil/trace.go:171","msg":"trace[514351622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8330; }","duration":"122.253295ms","start":"2026-08-20T01:33:42.322747Z","end":"2026-08-20T01:33:42.445001Z","steps":["trace[514351622] 'agreement among raft nodes before linearized reading' (duration: 122.158133ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:09.795644Z","caller":"traceutil/trace.go:171","msg":"trace[1128777749] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"254.288354ms","start":"2026-08-20T01:34:09.541335Z","end":"2026-08-20T01:34:09.795624Z","steps":["trace[1128777749] 'process raft request' (duration: 254.173761ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:09.975281Z","caller":"traceutil/trace.go:171","msg":"trace[80903357] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"287.982573ms","start":"2026-08-20T01:34:09.687267Z","end":"2026-08-20T01:34:09.975249Z","steps":["trace[80903357] 'process raft request' (duration: 246.831392ms)","trace[80903357] 'compare' (duration: 40.965237ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:34:23.370558Z","caller":"traceutil/trace.go:171","msg":"trace[1313102197] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8953; }","duration":"130.803792ms","start":"2026-08-20T01:34:23.239725Z","end":"2026-08-20T01:34:23.370529Z","steps":["trace[1313102197] 'read index received' (duration: 77.325541ms)","trace[1313102197] 'applied index is now lower than readState.Index' (duration: 53.47733ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:34:23.370845Z","caller":"traceutil/trace.go:171","msg":"trace[1057446453] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"132.76168ms","start":"2026-08-20T01:34:23.238068Z","end":"2026-08-20T01:34:23.37083Z","steps":["trace[1057446453] 'process raft request' (duration: 78.930461ms)","trace[1057446453] 'compare' (duration: 53.362197ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:34:23.371151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.397066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-nnfs8\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-20T01:34:23.371217Z","caller":"traceutil/trace.go:171","msg":"trace[1137674452] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-nnfs8; range_end:; response_count:1; response_revision:8644; }","duration":"131.511599ms","start":"2026-08-20T01:34:23.23969Z","end":"2026-08-20T01:34:23.371202Z","steps":["trace[1137674452] 'agreement among raft nodes before linearized reading' (duration: 131.200501ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:23.421111Z","caller":"traceutil/trace.go:171","msg":"trace[478972890] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"174.160006ms","start":"2026-08-20T01:34:23.246922Z","end":"2026-08-20T01:34:23.421082Z","steps":["trace[478972890] 'process raft request' (duration: 173.700005ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:24.241364Z","caller":"traceutil/trace.go:171","msg":"trace[1290073301] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"217.14426ms","start":"2026-08-20T01:34:24.024194Z","end":"2026-08-20T01:34:24.241339Z","steps":["trace[1290073301] 'process raft request' (duration: 216.990096ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:24.826552Z","caller":"traceutil/trace.go:171","msg":"trace[1990320267] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"145.312784ms","start":"2026-08-20T01:34:24.681207Z","end":"2026-08-20T01:34:24.826519Z","steps":["trace[1990320267] 'process raft request' (duration: 145.100699ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:38.369296Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ad.wal"} {"level":"info","ts":"2026-08-20T01:35:38.289254Z","caller":"traceutil/trace.go:171","msg":"trace[1977016119] transaction","detail":"{read_only:false; response_revision:9388; number_of_response:1; }","duration":"150.961682ms","start":"2026-08-20T01:35:38.138269Z","end":"2026-08-20T01:35:38.289231Z","steps":["trace[1977016119] 'process raft request' (duration: 150.746887ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:35:38.304282Z","caller":"traceutil/trace.go:171","msg":"trace[1191289545] transaction","detail":"{read_only:false; response_revision:9389; number_of_response:1; }","duration":"143.134212ms","start":"2026-08-20T01:35:38.161122Z","end":"2026-08-20T01:35:38.304256Z","steps":["trace[1191289545] 'process raft request' (duration: 142.992127ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:36:14.404757Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3363378c2227fa42","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:36:14.409767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T01:36:14.409879Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-20T01:38:37.438004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-08-20T01:38:37.513387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"73.372233ms","hash":2354121600,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":26509312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-20T01:38:37.513478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354121600,"revision":8302,"compact-revision":6084} {"level":"warn","ts":"2026-08-20T01:39:20.302535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.829551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:39:20.302669Z","caller":"traceutil/trace.go:171","msg":"trace[1098737484] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11228; }","duration":"150.031426ms","start":"2026-08-20T01:39:20.15261Z","end":"2026-08-20T01:39:20.302641Z","steps":["trace[1098737484] 'range keys from in-memory index tree' (duration: 149.75108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:39:20.302632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.772235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:39:20.302743Z","caller":"traceutil/trace.go:171","msg":"trace[1677632854] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:11228; }","duration":"150.960459ms","start":"2026-08-20T01:39:20.151757Z","end":"2026-08-20T01:39:20.302717Z","steps":["trace[1677632854] 'count revisions from in-memory index tree' (duration: 150.630671ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:39:20.636268Z","caller":"traceutil/trace.go:171","msg":"trace[338914931] linearizableReadLoop","detail":"{readStateIndex:11617; appliedIndex:11616; }","duration":"106.663506ms","start":"2026-08-20T01:39:20.529574Z","end":"2026-08-20T01:39:20.636238Z","steps":["trace[338914931] 'read index received' (duration: 29.540011ms)","trace[338914931] 'applied index is now lower than readState.Index' (duration: 77.118895ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:39:20.636334Z","caller":"traceutil/trace.go:171","msg":"trace[2117868622] transaction","detail":"{read_only:false; response_revision:11231; number_of_response:1; }","duration":"157.830718ms","start":"2026-08-20T01:39:20.478468Z","end":"2026-08-20T01:39:20.636298Z","steps":["trace[2117868622] 'process raft request' (duration: 80.737263ms)","trace[2117868622] 'compare' (duration: 76.89755ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:39:20.636522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.922972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-20T01:39:20.636569Z","caller":"traceutil/trace.go:171","msg":"trace[236911754] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:11231; }","duration":"107.021945ms","start":"2026-08-20T01:39:20.529537Z","end":"2026-08-20T01:39:20.636558Z","steps":["trace[236911754] 'agreement among raft nodes before linearized reading' (duration: 106.82494ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:39:21.207966Z","caller":"traceutil/trace.go:171","msg":"trace[397306175] transaction","detail":"{read_only:false; response_revision:11237; number_of_response:1; }","duration":"126.178284ms","start":"2026-08-20T01:39:21.081754Z","end":"2026-08-20T01:39:21.207932Z","steps":["trace[397306175] 'process raft request' (duration: 125.936318ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:43:37.445701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10798} {"level":"info","ts":"2026-08-20T01:43:37.524668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10798,"took":"75.046434ms","hash":438929970,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":27934720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T01:43:37.524745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":438929970,"revision":10798,"compact-revision":8302} {"level":"warn","ts":"2026-08-20T01:45:45.75613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.189009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-20T01:46:06.712255Z","caller":"traceutil/trace.go:171","msg":"trace[1812707049] transaction","detail":"{read_only:false; response_revision:14128; number_of_response:1; }","duration":"111.950016ms","start":"2026-08-20T01:46:06.600268Z","end":"2026-08-20T01:46:06.712218Z","steps":["trace[1812707049] 'process raft request' (duration: 111.768631ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:46:07.015151Z","caller":"traceutil/trace.go:171","msg":"trace[880238025] linearizableReadLoop","detail":"{readStateIndex:14621; appliedIndex:14620; }","duration":"242.275991ms","start":"2026-08-20T01:46:06.772845Z","end":"2026-08-20T01:46:07.015121Z","steps":["trace[880238025] 'read index received' (duration: 242.201389ms)","trace[880238025] 'applied index is now lower than readState.Index' (duration: 73.412µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:46:07.01529Z","caller":"traceutil/trace.go:171","msg":"trace[2022785216] transaction","detail":"{read_only:false; response_revision:14129; number_of_response:1; }","duration":"246.712799ms","start":"2026-08-20T01:46:06.76854Z","end":"2026-08-20T01:46:07.015253Z","steps":["trace[2022785216] 'process raft request' (duration: 246.439082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:46:07.015447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.586639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:46:07.015496Z","caller":"traceutil/trace.go:171","msg":"trace[1112901953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14129; }","duration":"242.6749ms","start":"2026-08-20T01:46:06.772806Z","end":"2026-08-20T01:46:07.015481Z","steps":["trace[1112901953] 'agreement among raft nodes before linearized reading' (duration: 242.447555ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:46:07.0227Z","caller":"traceutil/trace.go:171","msg":"trace[577941603] transaction","detail":"{read_only:false; response_revision:14130; number_of_response:1; }","duration":"192.631856ms","start":"2026-08-20T01:46:06.830041Z","end":"2026-08-20T01:46:07.022673Z","steps":["trace[577941603] 'process raft request' (duration: 192.457622ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:46:07.022897Z","caller":"traceutil/trace.go:171","msg":"trace[56893740] transaction","detail":"{read_only:false; response_revision:14131; number_of_response:1; }","duration":"192.109314ms","start":"2026-08-20T01:46:06.830754Z","end":"2026-08-20T01:46:07.022863Z","steps":["trace[56893740] 'process raft request' (duration: 191.876528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:46:07.022966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.086539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:46:07.02304Z","caller":"traceutil/trace.go:171","msg":"trace[295241540] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:14131; }","duration":"202.174552ms","start":"2026-08-20T01:46:06.820836Z","end":"2026-08-20T01:46:07.02301Z","steps":["trace[295241540] 'agreement among raft nodes before linearized reading' (duration: 201.994976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:46:07.023101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.441265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-20T01:46:07.023218Z","caller":"traceutil/trace.go:171","msg":"trace[724353863] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14131; }","duration":"118.595349ms","start":"2026-08-20T01:46:06.90459Z","end":"2026-08-20T01:46:07.023185Z","steps":["trace[724353863] 'agreement among raft nodes before linearized reading' (duration: 118.26529ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:37.460139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12975} {"level":"info","ts":"2026-08-20T01:48:37.539411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12975,"took":"76.291412ms","hash":1821272298,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T01:48:37.539489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1821272298,"revision":12975,"compact-revision":10798} {"level":"info","ts":"2026-08-20T01:53:04.727154Z","caller":"traceutil/trace.go:171","msg":"trace[418166108] transaction","detail":"{read_only:false; response_revision:17439; number_of_response:1; }","duration":"210.228752ms","start":"2026-08-20T01:53:04.516903Z","end":"2026-08-20T01:53:04.727131Z","steps":["trace[418166108] 'process raft request' (duration: 122.921927ms)","trace[418166108] 'compare' (duration: 87.195552ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:53:11.548837Z","caller":"traceutil/trace.go:171","msg":"trace[429876525] transaction","detail":"{read_only:false; response_revision:17480; number_of_response:1; }","duration":"255.57764ms","start":"2026-08-20T01:53:11.293226Z","end":"2026-08-20T01:53:11.548804Z","steps":["trace[429876525] 'process raft request' (duration: 255.332384ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:11.577089Z","caller":"traceutil/trace.go:171","msg":"trace[1053940157] linearizableReadLoop","detail":"{readStateIndex:18079; appliedIndex:18077; }","duration":"276.192049ms","start":"2026-08-20T01:53:11.300873Z","end":"2026-08-20T01:53:11.577066Z","steps":["trace[1053940157] 'read index received' (duration: 247.675799ms)","trace[1053940157] 'applied index is now lower than readState.Index' (duration: 28.51548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:53:11.577287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.402485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-20T01:53:11.577292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.081891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:53:11.577353Z","caller":"traceutil/trace.go:171","msg":"trace[1964135823] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:17481; }","duration":"206.200875ms","start":"2026-08-20T01:53:11.371133Z","end":"2026-08-20T01:53:11.577334Z","steps":["trace[1964135823] 'agreement among raft nodes before linearized reading' (duration: 206.088871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:11.577504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.545899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hpzgl\" ","response":"range_response_count:1 size:20762"} {"level":"info","ts":"2026-08-20T01:53:11.577636Z","caller":"traceutil/trace.go:171","msg":"trace[528064988] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hpzgl; range_end:; response_count:1; response_revision:17481; }","duration":"276.782104ms","start":"2026-08-20T01:53:11.300826Z","end":"2026-08-20T01:53:11.577608Z","steps":["trace[528064988] 'agreement among raft nodes before linearized reading' (duration: 276.335993ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:11.577312Z","caller":"traceutil/trace.go:171","msg":"trace[477644488] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17481; }","duration":"276.456307ms","start":"2026-08-20T01:53:11.300846Z","end":"2026-08-20T01:53:11.577302Z","steps":["trace[477644488] 'agreement among raft nodes before linearized reading' (duration: 276.320083ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:11.577176Z","caller":"traceutil/trace.go:171","msg":"trace[177097969] transaction","detail":"{read_only:false; response_revision:17481; number_of_response:1; }","duration":"280.874883ms","start":"2026-08-20T01:53:11.296248Z","end":"2026-08-20T01:53:11.577123Z","steps":["trace[177097969] 'process raft request' (duration: 280.696839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:12.03673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.985354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:53:12.036822Z","caller":"traceutil/trace.go:171","msg":"trace[2028087754] linearizableReadLoop","detail":"{readStateIndex:18081; appliedIndex:18080; }","duration":"376.88158ms","start":"2026-08-20T01:53:11.659926Z","end":"2026-08-20T01:53:12.036807Z","steps":["trace[2028087754] 'read index received' (duration: 254.72402ms)","trace[2028087754] 'applied index is now lower than readState.Index' (duration: 122.15663ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:53:12.036954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.285811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-20T01:53:12.03694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.008662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:53:12.036985Z","caller":"traceutil/trace.go:171","msg":"trace[928513373] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:17483; }","duration":"240.354423ms","start":"2026-08-20T01:53:11.796622Z","end":"2026-08-20T01:53:12.036976Z","steps":["trace[928513373] 'agreement among raft nodes before linearized reading' (duration: 240.276231ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:12.037002Z","caller":"traceutil/trace.go:171","msg":"trace[1746788539] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17483; }","duration":"377.114885ms","start":"2026-08-20T01:53:11.65987Z","end":"2026-08-20T01:53:12.036985Z","steps":["trace[1746788539] 'agreement among raft nodes before linearized reading' (duration: 377.005042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:12.037045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:53:11.659847Z","time spent":"377.188647ms","remote":"127.0.0.1:51292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-20T01:53:12.037499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.288594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-20T01:53:12.037617Z","caller":"traceutil/trace.go:171","msg":"trace[1950603949] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17483; }","duration":"236.408317ms","start":"2026-08-20T01:53:11.801191Z","end":"2026-08-20T01:53:12.037599Z","steps":["trace[1950603949] 'agreement among raft nodes before linearized reading' (duration: 236.158291ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:12.037593Z","caller":"traceutil/trace.go:171","msg":"trace[1390761627] transaction","detail":"{read_only:false; response_revision:17483; number_of_response:1; }","duration":"438.019661ms","start":"2026-08-20T01:53:11.599464Z","end":"2026-08-20T01:53:12.037483Z","steps":["trace[1390761627] 'process raft request' (duration: 315.171045ms)","trace[1390761627] 'compare' (duration: 121.8295ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:53:12.03826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:53:11.599436Z","time spent":"438.694777ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20877,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:53:19.766987Z","caller":"traceutil/trace.go:171","msg":"trace[318105113] linearizableReadLoop","detail":"{readStateIndex:18254; appliedIndex:18253; }","duration":"119.351772ms","start":"2026-08-20T01:53:19.64759Z","end":"2026-08-20T01:53:19.766942Z","steps":["trace[318105113] 'read index received' (duration: 114.35732ms)","trace[318105113] 'applied index is now lower than readState.Index' (duration: 4.993472ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:53:19.767111Z","caller":"traceutil/trace.go:171","msg":"trace[1571387070] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"120.361986ms","start":"2026-08-20T01:53:19.646714Z","end":"2026-08-20T01:53:19.767076Z","steps":["trace[1571387070] 'process raft request' (duration: 115.249042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:19.767251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.639108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-64d2dfe1-f20d-4828-8d96-d0d3590ea305\" ","response":"range_response_count:1 size:3640"} {"level":"info","ts":"2026-08-20T01:53:19.767336Z","caller":"traceutil/trace.go:171","msg":"trace[1947356978] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-64d2dfe1-f20d-4828-8d96-d0d3590ea305; range_end:; response_count:1; response_revision:17650; }","duration":"119.757392ms","start":"2026-08-20T01:53:19.64756Z","end":"2026-08-20T01:53:19.767318Z","steps":["trace[1947356978] 'agreement among raft nodes before linearized reading' (duration: 119.543887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:19.767348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.630793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-20T01:53:19.767406Z","caller":"traceutil/trace.go:171","msg":"trace[1711796797] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17650; }","duration":"118.753146ms","start":"2026-08-20T01:53:19.648636Z","end":"2026-08-20T01:53:19.767389Z","steps":["trace[1711796797] 'agreement among raft nodes before linearized reading' (duration: 118.517531ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:37.476011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15366} {"level":"info","ts":"2026-08-20T01:53:37.589521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15366,"took":"109.127744ms","hash":1519206152,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":32575488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-20T01:53:37.58958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1519206152,"revision":15366,"compact-revision":12975} {"level":"info","ts":"2026-08-20T01:56:57.626252Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3363378c2227fa42","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:56:57.631523Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T01:56:57.631727Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T01:58:37.494236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17776} {"level":"info","ts":"2026-08-20T01:58:37.608288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17776,"took":"110.276546ms","hash":2067335946,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":34021376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T01:58:37.6084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2067335946,"revision":17776,"compact-revision":15366} {"level":"info","ts":"2026-08-20T01:58:39.304675Z","caller":"traceutil/trace.go:171","msg":"trace[1108102102] transaction","detail":"{read_only:false; response_revision:20062; number_of_response:1; }","duration":"120.178684ms","start":"2026-08-20T01:58:39.184456Z","end":"2026-08-20T01:58:39.304634Z","steps":["trace[1108102102] 'process raft request' (duration: 119.923738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:58:55.687286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.47337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:58:55.687506Z","caller":"traceutil/trace.go:171","msg":"trace[1853840114] transaction","detail":"{read_only:false; response_revision:20147; number_of_response:1; }","duration":"181.763115ms","start":"2026-08-20T01:58:55.50572Z","end":"2026-08-20T01:58:55.687483Z","steps":["trace[1853840114] 'process raft request' (duration: 40.003167ms)","trace[1853840114] 'compare' (duration: 141.149913ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:58:56.138155Z","caller":"traceutil/trace.go:171","msg":"trace[634216297] transaction","detail":"{read_only:false; response_revision:20149; number_of_response:1; }","duration":"157.880848ms","start":"2026-08-20T01:58:55.980241Z","end":"2026-08-20T01:58:56.138122Z","steps":["trace[634216297] 'process raft request' (duration: 157.696743ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:58:56.218115Z","caller":"traceutil/trace.go:171","msg":"trace[892758879] linearizableReadLoop","detail":"{readStateIndex:20835; appliedIndex:20833; }","duration":"170.259757ms","start":"2026-08-20T01:58:56.047828Z","end":"2026-08-20T01:58:56.218087Z","steps":["trace[892758879] 'read index received' (duration: 90.20536ms)","trace[892758879] 'applied index is now lower than readState.Index' (duration: 80.053377ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:58:56.218242Z","caller":"traceutil/trace.go:171","msg":"trace[590885656] transaction","detail":"{read_only:false; response_revision:20150; number_of_response:1; }","duration":"169.744074ms","start":"2026-08-20T01:58:56.048418Z","end":"2026-08-20T01:58:56.218162Z","steps":["trace[590885656] 'process raft request' (duration: 169.492638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:58:56.218357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.514023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:58:56.218238Z","caller":"traceutil/trace.go:171","msg":"trace[1940662844] transaction","detail":"{read_only:false; response_revision:20151; number_of_response:1; }","duration":"146.207865ms","start":"2026-08-20T01:58:56.071986Z","end":"2026-08-20T01:58:56.218194Z","steps":["trace[1940662844] 'process raft request' (duration: 146.050641ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:58:56.218401Z","caller":"traceutil/trace.go:171","msg":"trace[974167376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20151; }","duration":"170.617316ms","start":"2026-08-20T01:58:56.047773Z","end":"2026-08-20T01:58:56.21839Z","steps":["trace[974167376] 'agreement among raft nodes before linearized reading' (duration: 170.446102ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:25.509227Z","caller":"traceutil/trace.go:171","msg":"trace[1249909857] transaction","detail":"{read_only:false; response_revision:22357; number_of_response:1; }","duration":"131.447728ms","start":"2026-08-20T02:02:25.377758Z","end":"2026-08-20T02:02:25.509206Z","steps":["trace[1249909857] 'process raft request' (duration: 131.289285ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:25.521288Z","caller":"traceutil/trace.go:171","msg":"trace[208801303] transaction","detail":"{read_only:false; response_revision:22358; number_of_response:1; }","duration":"100.807708ms","start":"2026-08-20T02:02:25.420456Z","end":"2026-08-20T02:02:25.521264Z","steps":["trace[208801303] 'process raft request' (duration: 100.650314ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:32.611993Z","caller":"traceutil/trace.go:171","msg":"trace[1840501519] transaction","detail":"{read_only:false; response_revision:22554; number_of_response:1; }","duration":"185.330432ms","start":"2026-08-20T02:02:32.426633Z","end":"2026-08-20T02:02:32.611963Z","steps":["trace[1840501519] 'process raft request' (duration: 185.165887ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:32.637721Z","caller":"traceutil/trace.go:171","msg":"trace[1415874286] transaction","detail":"{read_only:false; response_revision:22555; number_of_response:1; }","duration":"195.299623ms","start":"2026-08-20T02:02:32.442376Z","end":"2026-08-20T02:02:32.637676Z","steps":["trace[1415874286] 'process raft request' (duration: 195.149479ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:32.637978Z","caller":"traceutil/trace.go:171","msg":"trace[1257961545] linearizableReadLoop","detail":"{readStateIndex:23310; appliedIndex:23308; }","duration":"182.504543ms","start":"2026-08-20T02:02:32.455129Z","end":"2026-08-20T02:02:32.637634Z","steps":["trace[1257961545] 'read index received' (duration: 156.601346ms)","trace[1257961545] 'applied index is now lower than readState.Index' (duration: 25.901887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:02:32.638062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.900642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-20T02:02:32.638151Z","caller":"traceutil/trace.go:171","msg":"trace[312879429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22555; }","duration":"183.043266ms","start":"2026-08-20T02:02:32.455084Z","end":"2026-08-20T02:02:32.638127Z","steps":["trace[312879429] 'agreement among raft nodes before linearized reading' (duration: 182.733938ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:37.501086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20055} {"level":"info","ts":"2026-08-20T02:03:37.603822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20055,"took":"99.070987ms","hash":671296935,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":42713088,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-20T02:03:37.603878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":671296935,"revision":20055,"compact-revision":17776} {"level":"info","ts":"2026-08-20T02:08:37.507432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23182} {"level":"info","ts":"2026-08-20T02:08:37.641537Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23182,"took":"129.336557ms","hash":2861047730,"current-db-size-bytes":58150912,"current-db-size":"58 MB","current-db-size-in-use-bytes":32366592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T02:08:37.641654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2861047730,"revision":23182,"compact-revision":20055} {"level":"info","ts":"2026-08-20T02:10:42.331473Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b3b.wal"}