{"level":"warn","ts":"2026-08-31T06:54:20.32754Z","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-31T06:54:20.327692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.123: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.123:2380","--initial-cluster=instance=https://162.253.55.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.123: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-31T06:54:20.327821Z","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-31T06:54:20.327839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.123:2380"]} {"level":"info","ts":"2026-08-31T06:54:20.327864Z","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-31T06:54:20.328787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"]} {"level":"info","ts":"2026-08-31T06:54:20.328918Z","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.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.123: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-31T06:54:20.334068Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.778342ms"} {"level":"info","ts":"2026-08-31T06:54:20.348133Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dd65ba9af29ebfc9","cluster-id":"67ca29879725fb76"} {"level":"info","ts":"2026-08-31T06:54:20.34822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T06:54:20.34824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 0"} {"level":"info","ts":"2026-08-31T06:54:20.348255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dd65ba9af29ebfc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T06:54:20.348271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 1"} {"level":"info","ts":"2026-08-31T06:54:20.348313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"} {"level":"warn","ts":"2026-08-31T06:54:20.354356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T06:54:20.356263Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T06:54:20.357742Z","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-31T06:54:20.360192Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dd65ba9af29ebfc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T06:54:20.360485Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dd65ba9af29ebfc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T06:54:20.360634Z","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-31T06:54:20.360758Z","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-31T06:54:20.360804Z","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-31T06:54:20.36254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"} {"level":"info","ts":"2026-08-31T06:54:20.363004Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","added-peer-id":"dd65ba9af29ebfc9","added-peer-peer-urls":["https://162.253.55.123:2380"]} {"level":"info","ts":"2026-08-31T06:54:20.366674Z","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-31T06:54:20.36682Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.123:2380"} {"level":"info","ts":"2026-08-31T06:54:20.36685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.123:2380"} {"level":"info","ts":"2026-08-31T06:54:20.36707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dd65ba9af29ebfc9","initial-advertise-peer-urls":["https://162.253.55.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T06:54:20.367135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T06:54:20.648883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T06:54:20.649004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T06:54:20.649044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgPreVoteResp from dd65ba9af29ebfc9 at term 1"} {"level":"info","ts":"2026-08-31T06:54:20.649083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became candidate at term 2"} {"level":"info","ts":"2026-08-31T06:54:20.649095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgVoteResp from dd65ba9af29ebfc9 at term 2"} {"level":"info","ts":"2026-08-31T06:54:20.649119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became leader at term 2"} {"level":"info","ts":"2026-08-31T06:54:20.649134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dd65ba9af29ebfc9 elected leader dd65ba9af29ebfc9 at term 2"} {"level":"info","ts":"2026-08-31T06:54:20.650553Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:20.651434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dd65ba9af29ebfc9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.123:2379]}","request-path":"/0/members/dd65ba9af29ebfc9/attributes","cluster-id":"67ca29879725fb76","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T06:54:20.651879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T06:54:20.651925Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:20.652117Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:20.652197Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:20.652221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T06:54:20.652761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T06:54:20.652829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T06:54:20.655833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.123:2379"} {"level":"info","ts":"2026-08-31T06:54:20.656716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T06:54:35.930271Z","caller":"traceutil/trace.go:171","msg":"trace[2138765099] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"275.25927ms","start":"2026-08-31T06:54:35.654981Z","end":"2026-08-31T06:54:35.930241Z","steps":["trace[2138765099] 'read index received' (duration: 275.007875ms)","trace[2138765099] 'applied index is now lower than readState.Index' (duration: 250.445µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:54:35.930457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.443024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:54:35.930517Z","caller":"traceutil/trace.go:171","msg":"trace[202044369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"275.567177ms","start":"2026-08-31T06:54:35.65494Z","end":"2026-08-31T06:54:35.930507Z","steps":["trace[202044369] 'agreement among raft nodes before linearized reading' (duration: 275.447604ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:54:35.930714Z","caller":"traceutil/trace.go:171","msg":"trace[645446240] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"306.188726ms","start":"2026-08-31T06:54:35.624481Z","end":"2026-08-31T06:54:35.93067Z","steps":["trace[645446240] 'process raft request' (duration: 305.587953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:54:35.931631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T06:54:35.62446Z","time spent":"306.37769ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T06:54:36.221274Z","caller":"traceutil/trace.go:171","msg":"trace[1546430533] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"277.605299ms","start":"2026-08-31T06:54:35.94364Z","end":"2026-08-31T06:54:36.221246Z","steps":["trace[1546430533] 'process raft request' (duration: 266.488093ms)","trace[1546430533] 'compare' (duration: 10.878761ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:55:01.998198Z","caller":"traceutil/trace.go:171","msg":"trace[965207879] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"152.245012ms","start":"2026-08-31T06:55:01.845928Z","end":"2026-08-31T06:55:01.998173Z","steps":["trace[965207879] 'read index received' (duration: 151.925096ms)","trace[965207879] 'applied index is now lower than readState.Index' (duration: 319.176µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:55:01.998323Z","caller":"traceutil/trace.go:171","msg":"trace[121782307] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"249.887389ms","start":"2026-08-31T06:55:01.748395Z","end":"2026-08-31T06:55:01.998282Z","steps":["trace[121782307] 'process raft request' (duration: 249.556082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:55:01.998377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.383365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:55:01.998562Z","caller":"traceutil/trace.go:171","msg":"trace[1755435782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"152.647762ms","start":"2026-08-31T06:55:01.84588Z","end":"2026-08-31T06:55:01.998528Z","steps":["trace[1755435782] 'agreement among raft nodes before linearized reading' (duration: 152.402326ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:55:09.660114Z","caller":"traceutil/trace.go:171","msg":"trace[991739297] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"153.840756ms","start":"2026-08-31T06:55:09.50623Z","end":"2026-08-31T06:55:09.660071Z","steps":["trace[991739297] 'process raft request' (duration: 153.07164ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:55:10.136214Z","caller":"traceutil/trace.go:171","msg":"trace[1025569979] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"430.721981ms","start":"2026-08-31T06:55:09.705459Z","end":"2026-08-31T06:55:10.136181Z","steps":["trace[1025569979] 'process raft request' (duration: 430.319273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:55:10.136419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T06:55:09.705433Z","time spent":"430.886605ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T06:55:10.138152Z","caller":"traceutil/trace.go:171","msg":"trace[928447854] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"403.457349ms","start":"2026-08-31T06:55:09.734677Z","end":"2026-08-31T06:55:10.138135Z","steps":["trace[928447854] 'process raft request' (duration: 403.193604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:55:10.13824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T06:55:09.734639Z","time spent":"403.559592ms","remote":"127.0.0.1:45514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T06:55:10.151863Z","caller":"traceutil/trace.go:171","msg":"trace[377470742] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"306.6025ms","start":"2026-08-31T06:55:09.845232Z","end":"2026-08-31T06:55:10.151835Z","steps":["trace[377470742] 'read index received' (duration: 290.508866ms)","trace[377470742] 'applied index is now lower than readState.Index' (duration: 16.092054ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:55:10.152014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.760513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:55:10.152101Z","caller":"traceutil/trace.go:171","msg":"trace[214184964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"306.904346ms","start":"2026-08-31T06:55:09.845176Z","end":"2026-08-31T06:55:10.15208Z","steps":["trace[214184964] 'agreement among raft nodes before linearized reading' (duration: 306.748593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:55:10.15214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T06:55:09.845149Z","time spent":"306.984557ms","remote":"127.0.0.1:45048","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-31T06:55:10.152182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.15843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-31T06:55:10.152226Z","caller":"traceutil/trace.go:171","msg":"trace[1993688406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"107.273521ms","start":"2026-08-31T06:55:10.044938Z","end":"2026-08-31T06:55:10.152211Z","steps":["trace[1993688406] 'agreement among raft nodes before linearized reading' (duration: 107.061917ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:55:26.699814Z","caller":"traceutil/trace.go:171","msg":"trace[273758885] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"106.850903ms","start":"2026-08-31T06:55:26.592933Z","end":"2026-08-31T06:55:26.699784Z","steps":["trace[273758885] 'process raft request' (duration: 106.598178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:56:48.791256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.875944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:56:48.791403Z","caller":"traceutil/trace.go:171","msg":"trace[900873296] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1871; }","duration":"196.105538ms","start":"2026-08-31T06:56:48.595268Z","end":"2026-08-31T06:56:48.791373Z","steps":["trace[900873296] 'count revisions from in-memory index tree' (duration: 195.74194ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:56:48.791536Z","caller":"traceutil/trace.go:171","msg":"trace[1360422348] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"193.971061ms","start":"2026-08-31T06:56:48.597497Z","end":"2026-08-31T06:56:48.791468Z","steps":["trace[1360422348] 'process raft request' (duration: 161.373777ms)","trace[1360422348] 'compare' (duration: 32.159615ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:57:05.019282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.522178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T06:57:05.019436Z","caller":"traceutil/trace.go:171","msg":"trace[2107606758] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2085; }","duration":"135.439961ms","start":"2026-08-31T06:57:04.883976Z","end":"2026-08-31T06:57:05.019416Z","steps":["trace[2107606758] 'read index received' (duration: 7.412482ms)","trace[2107606758] 'applied index is now lower than readState.Index' (duration: 128.026029ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:57:05.019543Z","caller":"traceutil/trace.go:171","msg":"trace[243659270] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"159.141424ms","start":"2026-08-31T06:57:04.860363Z","end":"2026-08-31T06:57:05.019505Z","steps":["trace[243659270] 'process raft request' (duration: 31.089525ms)","trace[243659270] 'compare' (duration: 127.303293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:57:05.019736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.744968ms","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-31T06:57:05.019799Z","caller":"traceutil/trace.go:171","msg":"trace[970243127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2007; }","duration":"135.887591ms","start":"2026-08-31T06:57:04.883898Z","end":"2026-08-31T06:57:05.019785Z","steps":["trace[970243127] 'agreement among raft nodes before linearized reading' (duration: 135.641346ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:57:43.55901Z","caller":"traceutil/trace.go:171","msg":"trace[2133677696] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"119.429309ms","start":"2026-08-31T06:57:43.439548Z","end":"2026-08-31T06:57:43.558977Z","steps":["trace[2133677696] 'process raft request' (duration: 119.150832ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:58:58.632634Z","caller":"traceutil/trace.go:171","msg":"trace[1173413435] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"122.590645ms","start":"2026-08-31T06:58:58.510013Z","end":"2026-08-31T06:58:58.632603Z","steps":["trace[1173413435] 'process raft request' (duration: 122.420001ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:02:31.958484Z","caller":"traceutil/trace.go:171","msg":"trace[1544911692] linearizableReadLoop","detail":"{readStateIndex:4300; appliedIndex:4299; }","duration":"111.175334ms","start":"2026-08-31T07:02:31.847277Z","end":"2026-08-31T07:02:31.958452Z","steps":["trace[1544911692] 'read index received' (duration: 111.086462ms)","trace[1544911692] 'applied index is now lower than readState.Index' (duration: 87.212µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:02:31.958616Z","caller":"traceutil/trace.go:171","msg":"trace[925873390] transaction","detail":"{read_only:false; response_revision:4140; number_of_response:1; }","duration":"122.01047ms","start":"2026-08-31T07:02:31.836558Z","end":"2026-08-31T07:02:31.958569Z","steps":["trace[925873390] 'process raft request' (duration: 121.704474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:02:31.958704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.395419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:02:31.958747Z","caller":"traceutil/trace.go:171","msg":"trace[841412736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4140; }","duration":"111.490811ms","start":"2026-08-31T07:02:31.847245Z","end":"2026-08-31T07:02:31.958736Z","steps":["trace[841412736] 'agreement among raft nodes before linearized reading' (duration: 111.334847ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:57.857542Z","caller":"traceutil/trace.go:171","msg":"trace[2050133639] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"106.763608ms","start":"2026-08-31T07:03:57.750756Z","end":"2026-08-31T07:03:57.857519Z","steps":["trace[2050133639] 'process raft request' (duration: 106.542103ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:57.857488Z","caller":"traceutil/trace.go:171","msg":"trace[218568399] linearizableReadLoop","detail":"{readStateIndex:5329; appliedIndex:5328; }","duration":"100.821869ms","start":"2026-08-31T07:03:57.756637Z","end":"2026-08-31T07:03:57.857459Z","steps":["trace[218568399] 'read index received' (duration: 100.615044ms)","trace[218568399] 'applied index is now lower than readState.Index' (duration: 205.555µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:03:57.85781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.101955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:03:57.857943Z","caller":"traceutil/trace.go:171","msg":"trace[1227558543] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5130; }","duration":"101.309299ms","start":"2026-08-31T07:03:57.756607Z","end":"2026-08-31T07:03:57.857916Z","steps":["trace[1227558543] 'agreement among raft nodes before linearized reading' (duration: 101.040383ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.43816Z","caller":"traceutil/trace.go:171","msg":"trace[1069474007] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"108.553086ms","start":"2026-08-31T07:04:02.329551Z","end":"2026-08-31T07:04:02.438104Z","steps":["trace[1069474007] 'process raft request' (duration: 108.414823ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.438162Z","caller":"traceutil/trace.go:171","msg":"trace[1710572897] linearizableReadLoop","detail":"{readStateIndex:5411; appliedIndex:5405; }","duration":"106.951662ms","start":"2026-08-31T07:04:02.331164Z","end":"2026-08-31T07:04:02.438116Z","steps":["trace[1710572897] 'read index received' (duration: 6.765448ms)","trace[1710572897] 'applied index is now lower than readState.Index' (duration: 100.185063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:04:02.438471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.263848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-31T07:04:02.438494Z","caller":"traceutil/trace.go:171","msg":"trace[304007095] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"108.706709ms","start":"2026-08-31T07:04:02.329734Z","end":"2026-08-31T07:04:02.438441Z","steps":["trace[304007095] 'process raft request' (duration: 108.344522ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.438516Z","caller":"traceutil/trace.go:171","msg":"trace[1420723170] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5208; }","duration":"107.387571ms","start":"2026-08-31T07:04:02.331117Z","end":"2026-08-31T07:04:02.438505Z","steps":["trace[1420723170] 'agreement among raft nodes before linearized reading' (duration: 107.113705ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.438481Z","caller":"traceutil/trace.go:171","msg":"trace[351637009] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"108.704529ms","start":"2026-08-31T07:04:02.329725Z","end":"2026-08-31T07:04:02.438429Z","steps":["trace[351637009] 'process raft request' (duration: 108.310251ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.438529Z","caller":"traceutil/trace.go:171","msg":"trace[165301045] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"108.789181ms","start":"2026-08-31T07:04:02.329681Z","end":"2026-08-31T07:04:02.43847Z","steps":["trace[165301045] 'process raft request' (duration: 108.324221ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.438931Z","caller":"traceutil/trace.go:171","msg":"trace[903016499] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"109.451146ms","start":"2026-08-31T07:04:02.329463Z","end":"2026-08-31T07:04:02.438914Z","steps":["trace[903016499] 'process raft request' (duration: 108.344521ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:02.448658Z","caller":"traceutil/trace.go:171","msg":"trace[1876794955] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"108.834403ms","start":"2026-08-31T07:04:02.339802Z","end":"2026-08-31T07:04:02.448637Z","steps":["trace[1876794955] 'process raft request' (duration: 108.536707ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:03.601259Z","caller":"traceutil/trace.go:171","msg":"trace[1990417984] linearizableReadLoop","detail":"{readStateIndex:5524; appliedIndex:5523; }","duration":"104.145592ms","start":"2026-08-31T07:04:03.49707Z","end":"2026-08-31T07:04:03.601216Z","steps":["trace[1990417984] 'read index received' (duration: 103.931836ms)","trace[1990417984] 'applied index is now lower than readState.Index' (duration: 212.186µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:03.601783Z","caller":"traceutil/trace.go:171","msg":"trace[522302226] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"105.47036ms","start":"2026-08-31T07:04:03.496289Z","end":"2026-08-31T07:04:03.601759Z","steps":["trace[522302226] 'process raft request' (duration: 104.644881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:03.602216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.118863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62907"} {"level":"info","ts":"2026-08-31T07:04:03.60231Z","caller":"traceutil/trace.go:171","msg":"trace[738779660] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5317; }","duration":"105.303417ms","start":"2026-08-31T07:04:03.496985Z","end":"2026-08-31T07:04:03.602288Z","steps":["trace[738779660] 'agreement among raft nodes before linearized reading' (duration: 104.857067ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:03.604803Z","caller":"traceutil/trace.go:171","msg":"trace[272325251] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"105.151494ms","start":"2026-08-31T07:04:03.499628Z","end":"2026-08-31T07:04:03.604779Z","steps":["trace[272325251] 'process raft request' (duration: 104.586701ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:06.05727Z","caller":"traceutil/trace.go:171","msg":"trace[1483737203] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5573; }","duration":"218.582496ms","start":"2026-08-31T07:04:05.838655Z","end":"2026-08-31T07:04:06.057237Z","steps":["trace[1483737203] 'read index received' (duration: 218.277329ms)","trace[1483737203] 'applied index is now lower than readState.Index' (duration: 304.047µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:06.057386Z","caller":"traceutil/trace.go:171","msg":"trace[1640041536] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"258.859804ms","start":"2026-08-31T07:04:05.798482Z","end":"2026-08-31T07:04:06.057342Z","steps":["trace[1640041536] 'process raft request' (duration: 258.531717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:06.057623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.92703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:04:06.057702Z","caller":"traceutil/trace.go:171","msg":"trace[1291358421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5364; }","duration":"212.074723ms","start":"2026-08-31T07:04:05.845608Z","end":"2026-08-31T07:04:06.057683Z","steps":["trace[1291358421] 'agreement among raft nodes before linearized reading' (duration: 211.946601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:06.057946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.27151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787028"} {"level":"warn","ts":"2026-08-31T07:04:06.057975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.247894ms","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-31T07:04:06.057988Z","caller":"traceutil/trace.go:171","msg":"trace[180751340] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5364; }","duration":"219.377973ms","start":"2026-08-31T07:04:05.838597Z","end":"2026-08-31T07:04:06.057975Z","steps":["trace[180751340] 'agreement among raft nodes before linearized reading' (duration: 218.81082ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:06.058007Z","caller":"traceutil/trace.go:171","msg":"trace[406984226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5364; }","duration":"166.320556ms","start":"2026-08-31T07:04:05.891676Z","end":"2026-08-31T07:04:06.057997Z","steps":["trace[406984226] 'agreement among raft nodes before linearized reading' (duration: 166.199693ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:08.20221Z","caller":"traceutil/trace.go:171","msg":"trace[450083504] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"128.279667ms","start":"2026-08-31T07:04:08.073876Z","end":"2026-08-31T07:04:08.202155Z","steps":["trace[450083504] 'process raft request' (duration: 127.809097ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:20.902759Z","caller":"traceutil/trace.go:171","msg":"trace[1931074982] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"118.438653ms","start":"2026-08-31T07:04:20.784291Z","end":"2026-08-31T07:04:20.90273Z","steps":["trace[1931074982] 'process raft request' (duration: 41.013654ms)","trace[1931074982] 'compare' (duration: 77.129681ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:20.903255Z","caller":"traceutil/trace.go:171","msg":"trace[263782355] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"110.214584ms","start":"2026-08-31T07:04:20.792955Z","end":"2026-08-31T07:04:20.90317Z","steps":["trace[263782355] 'process raft request' (duration: 109.724143ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:21.273255Z","caller":"traceutil/trace.go:171","msg":"trace[99670441] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"277.718905ms","start":"2026-08-31T07:04:20.99551Z","end":"2026-08-31T07:04:21.273229Z","steps":["trace[99670441] 'process raft request' (duration: 243.239744ms)","trace[99670441] 'compare' (duration: 34.357929ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:21.277505Z","caller":"traceutil/trace.go:171","msg":"trace[735015498] linearizableReadLoop","detail":"{readStateIndex:5836; appliedIndex:5832; }","duration":"222.984881ms","start":"2026-08-31T07:04:21.054496Z","end":"2026-08-31T07:04:21.277481Z","steps":["trace[735015498] 'read index received' (duration: 184.235927ms)","trace[735015498] 'applied index is now lower than readState.Index' (duration: 38.747704ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:21.277839Z","caller":"traceutil/trace.go:171","msg":"trace[741368181] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"281.946017ms","start":"2026-08-31T07:04:20.995879Z","end":"2026-08-31T07:04:21.277825Z","steps":["trace[741368181] 'process raft request' (duration: 281.17721ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:21.278185Z","caller":"traceutil/trace.go:171","msg":"trace[1538054826] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"281.996278ms","start":"2026-08-31T07:04:20.996177Z","end":"2026-08-31T07:04:21.278173Z","steps":["trace[1538054826] 'process raft request' (duration: 281.019776ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:21.278339Z","caller":"traceutil/trace.go:171","msg":"trace[1374456089] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"282.11083ms","start":"2026-08-31T07:04:20.996216Z","end":"2026-08-31T07:04:21.278327Z","steps":["trace[1374456089] 'process raft request' (duration: 281.031736ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:21.278474Z","caller":"traceutil/trace.go:171","msg":"trace[1321878212] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"172.880659ms","start":"2026-08-31T07:04:21.105584Z","end":"2026-08-31T07:04:21.278464Z","steps":["trace[1321878212] 'process raft request' (duration: 171.701323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:21.279167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.632447ms","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-31T07:04:21.279216Z","caller":"traceutil/trace.go:171","msg":"trace[716703336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5622; }","duration":"224.74969ms","start":"2026-08-31T07:04:21.054455Z","end":"2026-08-31T07:04:21.279205Z","steps":["trace[716703336] 'agreement among raft nodes before linearized reading' (duration: 224.574017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:21.279412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.901674ms","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-31T07:04:21.279452Z","caller":"traceutil/trace.go:171","msg":"trace[469583388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5622; }","duration":"202.971074ms","start":"2026-08-31T07:04:21.076471Z","end":"2026-08-31T07:04:21.279442Z","steps":["trace[469583388] 'agreement among raft nodes before linearized reading' (duration: 202.877602ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:21.289388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2947} {"level":"info","ts":"2026-08-31T07:04:21.376028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2947,"took":"84.228817ms","hash":1596683991,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T07:04:21.376119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596683991,"revision":2947,"compact-revision":-1} {"level":"info","ts":"2026-08-31T07:04:29.472467Z","caller":"traceutil/trace.go:171","msg":"trace[199727143] linearizableReadLoop","detail":"{readStateIndex:6021; appliedIndex:6020; }","duration":"131.529878ms","start":"2026-08-31T07:04:29.340906Z","end":"2026-08-31T07:04:29.472436Z","steps":["trace[199727143] 'read index received' (duration: 40.83669ms)","trace[199727143] 'applied index is now lower than readState.Index' (duration: 90.691828ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:29.472573Z","caller":"traceutil/trace.go:171","msg":"trace[1173420500] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"154.551639ms","start":"2026-08-31T07:04:29.317988Z","end":"2026-08-31T07:04:29.472539Z","steps":["trace[1173420500] 'process raft request' (duration: 139.47671ms)","trace[1173420500] 'compare' (duration: 14.832664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:04:29.472738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.793833ms","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-31T07:04:29.472795Z","caller":"traceutil/trace.go:171","msg":"trace[1908747583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5799; }","duration":"131.886085ms","start":"2026-08-31T07:04:29.340897Z","end":"2026-08-31T07:04:29.472783Z","steps":["trace[1908747583] 'agreement among raft nodes before linearized reading' (duration: 131.700101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:29.4728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.543777ms","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-31T07:04:29.472852Z","caller":"traceutil/trace.go:171","msg":"trace[445336525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5799; }","duration":"114.64131ms","start":"2026-08-31T07:04:29.358197Z","end":"2026-08-31T07:04:29.472838Z","steps":["trace[445336525] 'agreement among raft nodes before linearized reading' (duration: 114.459206ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:04:29.671953Z","caller":"traceutil/trace.go:171","msg":"trace[627325621] transaction","detail":"{read_only:false; response_revision:5801; number_of_response:1; }","duration":"194.218464ms","start":"2026-08-31T07:04:29.477706Z","end":"2026-08-31T07:04:29.671925Z","steps":["trace[627325621] 'process raft request' (duration: 104.201161ms)","trace[627325621] 'compare' (duration: 89.86012ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:29.672072Z","caller":"traceutil/trace.go:171","msg":"trace[2117945416] linearizableReadLoop","detail":"{readStateIndex:6024; appliedIndex:6021; }","duration":"180.781662ms","start":"2026-08-31T07:04:29.49125Z","end":"2026-08-31T07:04:29.672032Z","steps":["trace[2117945416] 'read index received' (duration: 27.788666ms)","trace[2117945416] 'applied index is now lower than readState.Index' (duration: 152.989936ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:29.672114Z","caller":"traceutil/trace.go:171","msg":"trace[1775751631] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"194.324106ms","start":"2026-08-31T07:04:29.477743Z","end":"2026-08-31T07:04:29.672068Z","steps":["trace[1775751631] 'process raft request' (duration: 194.137722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:04:29.672356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.087758ms","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-31T07:04:29.672399Z","caller":"traceutil/trace.go:171","msg":"trace[1179148394] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5802; }","duration":"181.15459ms","start":"2026-08-31T07:04:29.491232Z","end":"2026-08-31T07:04:29.672387Z","steps":["trace[1179148394] 'agreement among raft nodes before linearized reading' (duration: 180.929755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:09.974613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.337274ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-31T07:08:10.551926Z","caller":"traceutil/trace.go:171","msg":"trace[263455706] linearizableReadLoop","detail":"{readStateIndex:7648; appliedIndex:7647; }","duration":"125.91628ms","start":"2026-08-31T07:08:10.425971Z","end":"2026-08-31T07:08:10.551888Z","steps":["trace[263455706] 'read index received' (duration: 104.060699ms)","trace[263455706] 'applied index is now lower than readState.Index' (duration: 21.853661ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:10.552127Z","caller":"traceutil/trace.go:171","msg":"trace[1609797777] transaction","detail":"{read_only:false; response_revision:7375; number_of_response:1; }","duration":"128.306992ms","start":"2026-08-31T07:08:10.42377Z","end":"2026-08-31T07:08:10.552077Z","steps":["trace[1609797777] 'process raft request' (duration: 106.334739ms)","trace[1609797777] 'compare' (duration: 21.534003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:10.552356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.342778ms","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-31T07:08:10.552432Z","caller":"traceutil/trace.go:171","msg":"trace[1031458391] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7375; }","duration":"126.455201ms","start":"2026-08-31T07:08:10.425956Z","end":"2026-08-31T07:08:10.552411Z","steps":["trace[1031458391] 'agreement among raft nodes before linearized reading' (duration: 126.084973ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.001669Z","caller":"traceutil/trace.go:171","msg":"trace[1432156478] transaction","detail":"{read_only:false; response_revision:7389; number_of_response:1; }","duration":"102.844264ms","start":"2026-08-31T07:08:10.89878Z","end":"2026-08-31T07:08:11.001625Z","steps":["trace[1432156478] 'process raft request' (duration: 21.183676ms)","trace[1432156478] 'compare' (duration: 81.448413ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.006678Z","caller":"traceutil/trace.go:171","msg":"trace[722732789] linearizableReadLoop","detail":"{readStateIndex:7663; appliedIndex:7661; }","duration":"102.761801ms","start":"2026-08-31T07:08:10.903875Z","end":"2026-08-31T07:08:11.006637Z","steps":["trace[722732789] 'read index received' (duration: 15.980203ms)","trace[722732789] 'applied index is now lower than readState.Index' (duration: 86.780128ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.007109Z","caller":"traceutil/trace.go:171","msg":"trace[583990113] transaction","detail":"{read_only:false; response_revision:7390; number_of_response:1; }","duration":"107.969374ms","start":"2026-08-31T07:08:10.899118Z","end":"2026-08-31T07:08:11.007087Z","steps":["trace[583990113] 'process raft request' (duration: 107.310629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:11.00765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.747113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-08-31T07:08:11.007711Z","caller":"traceutil/trace.go:171","msg":"trace[791299654] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:7390; }","duration":"105.064662ms","start":"2026-08-31T07:08:10.902624Z","end":"2026-08-31T07:08:11.007689Z","steps":["trace[791299654] 'agreement among raft nodes before linearized reading' (duration: 104.750315ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.202339Z","caller":"traceutil/trace.go:171","msg":"trace[223652898] linearizableReadLoop","detail":"{readStateIndex:7670; appliedIndex:7669; }","duration":"109.759822ms","start":"2026-08-31T07:08:11.092537Z","end":"2026-08-31T07:08:11.202297Z","steps":["trace[223652898] 'read index received' (duration: 20.376858ms)","trace[223652898] 'applied index is now lower than readState.Index' (duration: 89.381604ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.202413Z","caller":"traceutil/trace.go:171","msg":"trace[1439999580] transaction","detail":"{read_only:false; response_revision:7397; number_of_response:1; }","duration":"160.162936ms","start":"2026-08-31T07:08:11.042201Z","end":"2026-08-31T07:08:11.202364Z","steps":["trace[1439999580] 'process raft request' (duration: 70.69162ms)","trace[1439999580] 'compare' (duration: 89.1994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:11.202692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.846958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-31T07:08:11.202743Z","caller":"traceutil/trace.go:171","msg":"trace[356485656] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7397; }","duration":"158.95176ms","start":"2026-08-31T07:08:11.043774Z","end":"2026-08-31T07:08:11.202725Z","steps":["trace[356485656] 'agreement among raft nodes before linearized reading' (duration: 158.696374ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.20813Z","caller":"traceutil/trace.go:171","msg":"trace[1843557448] transaction","detail":"{read_only:false; response_revision:7398; number_of_response:1; }","duration":"109.18391ms","start":"2026-08-31T07:08:11.098894Z","end":"2026-08-31T07:08:11.208078Z","steps":["trace[1843557448] 'process raft request' (duration: 108.983236ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.5175Z","caller":"traceutil/trace.go:171","msg":"trace[802021223] transaction","detail":"{read_only:false; response_revision:7406; number_of_response:1; }","duration":"106.477151ms","start":"2026-08-31T07:08:11.410983Z","end":"2026-08-31T07:08:11.51746Z","steps":["trace[802021223] 'process raft request' (duration: 27.874ms)","trace[802021223] 'compare' (duration: 78.385477ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.564524Z","caller":"traceutil/trace.go:171","msg":"trace[2067115710] linearizableReadLoop","detail":"{readStateIndex:7680; appliedIndex:7678; }","duration":"147.876813ms","start":"2026-08-31T07:08:11.416613Z","end":"2026-08-31T07:08:11.56449Z","steps":["trace[2067115710] 'read index received' (duration: 22.26435ms)","trace[2067115710] 'applied index is now lower than readState.Index' (duration: 125.610903ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.564742Z","caller":"traceutil/trace.go:171","msg":"trace[453268953] transaction","detail":"{read_only:false; response_revision:7408; number_of_response:1; }","duration":"146.208897ms","start":"2026-08-31T07:08:11.418519Z","end":"2026-08-31T07:08:11.564728Z","steps":["trace[453268953] 'process raft request' (duration: 145.871129ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.56476Z","caller":"traceutil/trace.go:171","msg":"trace[1131135625] transaction","detail":"{read_only:false; response_revision:7407; number_of_response:1; }","duration":"150.5559ms","start":"2026-08-31T07:08:11.414177Z","end":"2026-08-31T07:08:11.564733Z","steps":["trace[1131135625] 'process raft request' (duration: 150.029618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:11.565156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.518816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-31T07:08:11.565238Z","caller":"traceutil/trace.go:171","msg":"trace[227323656] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:7408; }","duration":"148.652899ms","start":"2026-08-31T07:08:11.416564Z","end":"2026-08-31T07:08:11.565217Z","steps":["trace[227323656] 'agreement among raft nodes before linearized reading' (duration: 148.404014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:11.565221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.476406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-31T07:08:11.565321Z","caller":"traceutil/trace.go:171","msg":"trace[716329475] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:7408; }","duration":"148.630249ms","start":"2026-08-31T07:08:11.416661Z","end":"2026-08-31T07:08:11.565292Z","steps":["trace[716329475] 'agreement among raft nodes before linearized reading' (duration: 148.329603ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.690054Z","caller":"traceutil/trace.go:171","msg":"trace[378585580] linearizableReadLoop","detail":"{readStateIndex:7682; appliedIndex:7681; }","duration":"114.713199ms","start":"2026-08-31T07:08:11.575287Z","end":"2026-08-31T07:08:11.69Z","steps":["trace[378585580] 'read index received' (duration: 127.823µs)","trace[378585580] 'applied index is now lower than readState.Index' (duration: 114.583726ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:11.690157Z","caller":"traceutil/trace.go:171","msg":"trace[1437490811] transaction","detail":"{read_only:false; response_revision:7409; number_of_response:1; }","duration":"114.860012ms","start":"2026-08-31T07:08:11.575249Z","end":"2026-08-31T07:08:11.690109Z","steps":["trace[1437490811] 'process raft request' (duration: 43.095008ms)","trace[1437490811] 'compare' (duration: 71.491348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:11.6903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.690849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:11.690379Z","caller":"traceutil/trace.go:171","msg":"trace[1129138108] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:7409; }","duration":"114.801671ms","start":"2026-08-31T07:08:11.575551Z","end":"2026-08-31T07:08:11.690352Z","steps":["trace[1129138108] 'agreement among raft nodes before linearized reading' (duration: 114.675078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:11.690488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.095617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-08-31T07:08:11.690568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.834876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-31T07:08:11.690618Z","caller":"traceutil/trace.go:171","msg":"trace[891925947] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:7409; }","duration":"110.919398ms","start":"2026-08-31T07:08:11.579686Z","end":"2026-08-31T07:08:11.690605Z","steps":["trace[891925947] 'agreement among raft nodes before linearized reading' (duration: 110.712883ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:11.690695Z","caller":"traceutil/trace.go:171","msg":"trace[418013407] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:7409; }","duration":"115.360124ms","start":"2026-08-31T07:08:11.575241Z","end":"2026-08-31T07:08:11.690601Z","steps":["trace[418013407] 'agreement among raft nodes before linearized reading' (duration: 114.935644ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:12.060685Z","caller":"traceutil/trace.go:171","msg":"trace[500516888] linearizableReadLoop","detail":"{readStateIndex:7685; appliedIndex:7683; }","duration":"302.05069ms","start":"2026-08-31T07:08:11.758607Z","end":"2026-08-31T07:08:12.060658Z","steps":["trace[500516888] 'read index received' (duration: 69.092736ms)","trace[500516888] 'applied index is now lower than readState.Index' (duration: 232.955834ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:12.060695Z","caller":"traceutil/trace.go:171","msg":"trace[1301766082] transaction","detail":"{read_only:false; response_revision:7412; number_of_response:1; }","duration":"302.151812ms","start":"2026-08-31T07:08:11.758486Z","end":"2026-08-31T07:08:12.060638Z","steps":["trace[1301766082] 'process raft request' (duration: 218.496732ms)","trace[1301766082] 'compare' (duration: 83.21675ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:12.060741Z","caller":"traceutil/trace.go:171","msg":"trace[1398878429] transaction","detail":"{read_only:false; response_revision:7413; number_of_response:1; }","duration":"300.16703ms","start":"2026-08-31T07:08:11.760528Z","end":"2026-08-31T07:08:12.060695Z","steps":["trace[1398878429] 'process raft request' (duration: 299.834253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.06099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.760516Z","time spent":"300.326223ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9163,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:08:12.061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.373137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-31T07:08:12.061076Z","caller":"traceutil/trace.go:171","msg":"trace[1175459239] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:7414; }","duration":"302.5229ms","start":"2026-08-31T07:08:11.758541Z","end":"2026-08-31T07:08:12.061064Z","steps":["trace[1175459239] 'agreement among raft nodes before linearized reading' (duration: 302.247654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.061078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.758458Z","time spent":"302.347397ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:08:12.061109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.758524Z","time spent":"302.576842ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-08-31T07:08:12.106761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.380585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:12.106849Z","caller":"traceutil/trace.go:171","msg":"trace[2140438549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7415; }","duration":"261.546629ms","start":"2026-08-31T07:08:11.845283Z","end":"2026-08-31T07:08:12.10683Z","steps":["trace[2140438549] 'agreement among raft nodes before linearized reading' (duration: 261.306244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.106928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.178577ms","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-31T07:08:12.107027Z","caller":"traceutil/trace.go:171","msg":"trace[1721278094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7415; }","duration":"331.33529ms","start":"2026-08-31T07:08:11.775655Z","end":"2026-08-31T07:08:12.106991Z","steps":["trace[1721278094] 'agreement among raft nodes before linearized reading' (duration: 330.967322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.107071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.775621Z","time spent":"331.438223ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-31T07:08:12.107351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.872484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"warn","ts":"2026-08-31T07:08:12.107347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.869575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" ","response":"range_response_count:1 size:3296"} {"level":"info","ts":"2026-08-31T07:08:12.107392Z","caller":"traceutil/trace.go:171","msg":"trace[1086315245] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:7415; }","duration":"339.947205ms","start":"2026-08-31T07:08:11.767434Z","end":"2026-08-31T07:08:12.107381Z","steps":["trace[1086315245] 'agreement among raft nodes before linearized reading' (duration: 339.807993ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:12.107419Z","caller":"traceutil/trace.go:171","msg":"trace[1087529314] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican; range_end:; response_count:1; response_revision:7415; }","duration":"346.959857ms","start":"2026-08-31T07:08:11.760435Z","end":"2026-08-31T07:08:12.107395Z","steps":["trace[1087529314] 'agreement among raft nodes before linearized reading' (duration: 346.776263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.107428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.767419Z","time spent":"339.993056ms","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4749,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-08-31T07:08:12.107475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.760421Z","time spent":"347.040668ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3319,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" "} {"level":"warn","ts":"2026-08-31T07:08:12.10759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.092101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112025"} {"level":"info","ts":"2026-08-31T07:08:12.107651Z","caller":"traceutil/trace.go:171","msg":"trace[1681173406] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:7415; }","duration":"348.177113ms","start":"2026-08-31T07:08:11.75946Z","end":"2026-08-31T07:08:12.107637Z","steps":["trace[1681173406] 'agreement among raft nodes before linearized reading' (duration: 347.844506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.107683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:11.75945Z","time spent":"348.224384ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":16,"response size":112048,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-08-31T07:08:12.553055Z","caller":"traceutil/trace.go:171","msg":"trace[756862654] transaction","detail":"{read_only:false; response_revision:7432; number_of_response:1; }","duration":"115.500066ms","start":"2026-08-31T07:08:12.437516Z","end":"2026-08-31T07:08:12.553016Z","steps":["trace[756862654] 'process raft request' (duration: 106.908731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:12.553209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.837197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-08-31T07:08:12.553309Z","caller":"traceutil/trace.go:171","msg":"trace[347481774] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7432; }","duration":"118.961359ms","start":"2026-08-31T07:08:12.434321Z","end":"2026-08-31T07:08:12.553283Z","steps":["trace[347481774] 'agreement among raft nodes before linearized reading' (duration: 118.672453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:15.126114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.747314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:15.126234Z","caller":"traceutil/trace.go:171","msg":"trace[532553109] linearizableReadLoop","detail":"{readStateIndex:7726; appliedIndex:7725; }","duration":"372.887064ms","start":"2026-08-31T07:08:14.753328Z","end":"2026-08-31T07:08:15.126215Z","steps":["trace[532553109] 'read index received' (duration: 70.826014ms)","trace[532553109] 'applied index is now lower than readState.Index' (duration: 302.05955ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:15.12626Z","caller":"traceutil/trace.go:171","msg":"trace[213470926] transaction","detail":"{read_only:false; response_revision:7453; number_of_response:1; }","duration":"631.538171ms","start":"2026-08-31T07:08:14.494689Z","end":"2026-08-31T07:08:15.126228Z","steps":["trace[213470926] 'process raft request' (duration: 329.555282ms)","trace[213470926] 'compare' (duration: 301.603041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:15.126417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.098888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.123\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-31T07:08:15.126414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:14.494658Z","time spent":"631.674804ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:15.126458Z","caller":"traceutil/trace.go:171","msg":"trace[741358090] range","detail":"{range_begin:/registry/masterleases/162.253.55.123; range_end:; response_count:1; response_revision:7453; }","duration":"373.17271ms","start":"2026-08-31T07:08:14.75327Z","end":"2026-08-31T07:08:15.126443Z","steps":["trace[741358090] 'agreement among raft nodes before linearized reading' (duration: 373.033627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:15.126497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:14.753245Z","time spent":"373.244212ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.123\" "} {"level":"warn","ts":"2026-08-31T07:08:15.126465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.253751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:15.126576Z","caller":"traceutil/trace.go:171","msg":"trace[919385477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7453; }","duration":"280.415594ms","start":"2026-08-31T07:08:14.846129Z","end":"2026-08-31T07:08:15.126544Z","steps":["trace[919385477] 'agreement among raft nodes before linearized reading' (duration: 280.232791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:15.127033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.137694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:15.12716Z","caller":"traceutil/trace.go:171","msg":"trace[505287146] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:7453; }","duration":"152.354649ms","start":"2026-08-31T07:08:14.97477Z","end":"2026-08-31T07:08:15.127124Z","steps":["trace[505287146] 'agreement among raft nodes before linearized reading' (duration: 152.107253ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:15.28004Z","caller":"traceutil/trace.go:171","msg":"trace[2057484953] transaction","detail":"{read_only:false; response_revision:7454; number_of_response:1; }","duration":"148.574428ms","start":"2026-08-31T07:08:15.131365Z","end":"2026-08-31T07:08:15.279939Z","steps":["trace[2057484953] 'process raft request' (duration: 147.986265ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:15.350214Z","caller":"traceutil/trace.go:171","msg":"trace[291332017] transaction","detail":"{read_only:false; response_revision:7456; number_of_response:1; }","duration":"217.902ms","start":"2026-08-31T07:08:15.13229Z","end":"2026-08-31T07:08:15.350192Z","steps":["trace[291332017] 'process raft request' (duration: 217.751296ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:15.350215Z","caller":"traceutil/trace.go:171","msg":"trace[850426157] transaction","detail":"{read_only:false; response_revision:7455; number_of_response:1; }","duration":"218.248497ms","start":"2026-08-31T07:08:15.131928Z","end":"2026-08-31T07:08:15.350176Z","steps":["trace[850426157] 'process raft request' (duration: 217.935161ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:15.350374Z","caller":"traceutil/trace.go:171","msg":"trace[66362070] transaction","detail":"{read_only:false; response_revision:7457; number_of_response:1; }","duration":"218.015322ms","start":"2026-08-31T07:08:15.132319Z","end":"2026-08-31T07:08:15.350335Z","steps":["trace[66362070] 'process raft request' (duration: 217.766706ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:15.350749Z","caller":"traceutil/trace.go:171","msg":"trace[862629303] transaction","detail":"{read_only:false; response_revision:7458; number_of_response:1; }","duration":"214.812863ms","start":"2026-08-31T07:08:15.135923Z","end":"2026-08-31T07:08:15.350736Z","steps":["trace[862629303] 'process raft request' (duration: 214.20133ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:16.265626Z","caller":"traceutil/trace.go:171","msg":"trace[524999360] transaction","detail":"{read_only:false; response_revision:7461; number_of_response:1; }","duration":"136.908937ms","start":"2026-08-31T07:08:16.128673Z","end":"2026-08-31T07:08:16.265582Z","steps":["trace[524999360] 'process raft request' (duration: 136.61256ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:16.292337Z","caller":"traceutil/trace.go:171","msg":"trace[1168193352] transaction","detail":"{read_only:false; response_revision:7462; number_of_response:1; }","duration":"149.590299ms","start":"2026-08-31T07:08:16.142716Z","end":"2026-08-31T07:08:16.292306Z","steps":["trace[1168193352] 'process raft request' (duration: 149.276512ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:18.758267Z","caller":"traceutil/trace.go:171","msg":"trace[448222661] transaction","detail":"{read_only:false; response_revision:7480; number_of_response:1; }","duration":"136.105338ms","start":"2026-08-31T07:08:18.622123Z","end":"2026-08-31T07:08:18.758228Z","steps":["trace[448222661] 'process raft request' (duration: 87.36203ms)","trace[448222661] 'compare' (duration: 48.554034ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:20.544903Z","caller":"traceutil/trace.go:171","msg":"trace[72503818] transaction","detail":"{read_only:false; response_revision:7498; number_of_response:1; }","duration":"153.294379ms","start":"2026-08-31T07:08:20.391545Z","end":"2026-08-31T07:08:20.544839Z","steps":["trace[72503818] 'process raft request' (duration: 60.052853ms)","trace[72503818] 'compare' (duration: 93.073663ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:20.552225Z","caller":"traceutil/trace.go:171","msg":"trace[119638617] transaction","detail":"{read_only:false; response_revision:7499; number_of_response:1; }","duration":"101.537615ms","start":"2026-08-31T07:08:20.45066Z","end":"2026-08-31T07:08:20.552198Z","steps":["trace[119638617] 'process raft request' (duration: 101.371511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.136942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.884487ms","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-31T07:08:21.137056Z","caller":"traceutil/trace.go:171","msg":"trace[975337228] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7501; }","duration":"125.847978ms","start":"2026-08-31T07:08:21.011183Z","end":"2026-08-31T07:08:21.137031Z","steps":["trace[975337228] 'range keys from in-memory index tree' (duration: 124.651931ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.560823Z","caller":"traceutil/trace.go:171","msg":"trace[648819783] transaction","detail":"{read_only:false; response_revision:7503; number_of_response:1; }","duration":"146.569143ms","start":"2026-08-31T07:08:21.41423Z","end":"2026-08-31T07:08:21.560799Z","steps":["trace[648819783] 'process raft request' (duration: 146.41258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.613567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.276183ms","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-31T07:08:21.61385Z","caller":"traceutil/trace.go:171","msg":"trace[770711040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7504; }","duration":"143.59151ms","start":"2026-08-31T07:08:21.470236Z","end":"2026-08-31T07:08:21.613827Z","steps":["trace[770711040] 'agreement among raft nodes before linearized reading' (duration: 143.158101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.613875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.52999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:08:21.613985Z","caller":"traceutil/trace.go:171","msg":"trace[1373395483] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:7504; }","duration":"117.667083ms","start":"2026-08-31T07:08:21.496297Z","end":"2026-08-31T07:08:21.613964Z","steps":["trace[1373395483] 'agreement among raft nodes before linearized reading' (duration: 117.271414ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.613479Z","caller":"traceutil/trace.go:171","msg":"trace[1265141662] transaction","detail":"{read_only:false; response_revision:7504; number_of_response:1; }","duration":"114.636397ms","start":"2026-08-31T07:08:21.498771Z","end":"2026-08-31T07:08:21.613407Z","steps":["trace[1265141662] 'process raft request' (duration: 80.686156ms)","trace[1265141662] 'compare' (duration: 33.774807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:21.614551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.606438ms","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-31T07:08:21.614613Z","caller":"traceutil/trace.go:171","msg":"trace[1069718711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7504; }","duration":"115.68878ms","start":"2026-08-31T07:08:21.498907Z","end":"2026-08-31T07:08:21.614596Z","steps":["trace[1069718711] 'agreement among raft nodes before linearized reading' (duration: 115.514126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.615035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.818148ms","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-31T07:08:21.615093Z","caller":"traceutil/trace.go:171","msg":"trace[1427455608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7504; }","duration":"125.955911ms","start":"2026-08-31T07:08:21.489121Z","end":"2026-08-31T07:08:21.615077Z","steps":["trace[1427455608] 'agreement among raft nodes before linearized reading' (duration: 124.746246ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.399624Z","caller":"traceutil/trace.go:171","msg":"trace[1203875238] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"150.064909ms","start":"2026-08-31T07:08:22.249519Z","end":"2026-08-31T07:08:22.399584Z","steps":["trace[1203875238] 'process raft request' (duration: 123.697222ms)","trace[1203875238] 'compare' (duration: 26.092871ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.399707Z","caller":"traceutil/trace.go:171","msg":"trace[395810550] linearizableReadLoop","detail":"{readStateIndex:7790; appliedIndex:7785; }","duration":"135.2305ms","start":"2026-08-31T07:08:22.264447Z","end":"2026-08-31T07:08:22.399677Z","steps":["trace[395810550] 'read index received' (duration: 3.489425ms)","trace[395810550] 'applied index is now lower than readState.Index' (duration: 131.739805ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.399837Z","caller":"traceutil/trace.go:171","msg":"trace[1931390565] transaction","detail":"{read_only:false; response_revision:7513; number_of_response:1; }","duration":"149.64357ms","start":"2026-08-31T07:08:22.250177Z","end":"2026-08-31T07:08:22.399821Z","steps":["trace[1931390565] 'process raft request' (duration: 149.369644ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.399955Z","caller":"traceutil/trace.go:171","msg":"trace[2075790640] transaction","detail":"{read_only:false; response_revision:7512; number_of_response:1; }","duration":"149.896266ms","start":"2026-08-31T07:08:22.250036Z","end":"2026-08-31T07:08:22.399932Z","steps":["trace[2075790640] 'process raft request' (duration: 149.442486ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.400041Z","caller":"traceutil/trace.go:171","msg":"trace[427113474] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"149.591849ms","start":"2026-08-31T07:08:22.250393Z","end":"2026-08-31T07:08:22.399985Z","steps":["trace[427113474] 'process raft request' (duration: 149.225401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.400105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.641109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:22.400189Z","caller":"traceutil/trace.go:171","msg":"trace[167710588] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:7514; }","duration":"135.727101ms","start":"2026-08-31T07:08:22.264428Z","end":"2026-08-31T07:08:22.400155Z","steps":["trace[167710588] 'agreement among raft nodes before linearized reading' (duration: 135.563277ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.79268Z","caller":"traceutil/trace.go:171","msg":"trace[176152452] transaction","detail":"{read_only:false; response_revision:7516; number_of_response:1; }","duration":"234.578549ms","start":"2026-08-31T07:08:22.558086Z","end":"2026-08-31T07:08:22.792665Z","steps":["trace[176152452] 'process raft request' (duration: 234.508457ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.79266Z","caller":"traceutil/trace.go:171","msg":"trace[1782391874] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"399.218482ms","start":"2026-08-31T07:08:22.393405Z","end":"2026-08-31T07:08:22.792623Z","steps":["trace[1782391874] 'process raft request' (duration: 325.706199ms)","trace[1782391874] 'compare' (duration: 72.325047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:22.79298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:22.393379Z","time spent":"399.469077ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:22.792737Z","caller":"traceutil/trace.go:171","msg":"trace[255472002] linearizableReadLoop","detail":"{readStateIndex:7791; appliedIndex:7790; }","duration":"392.924597ms","start":"2026-08-31T07:08:22.399779Z","end":"2026-08-31T07:08:22.792704Z","steps":["trace[255472002] 'read index received' (duration: 319.341382ms)","trace[255472002] 'applied index is now lower than readState.Index' (duration: 73.579585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:22.79317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.435589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-08-31T07:08:22.793271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.932033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-31T07:08:22.793317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.048286ms","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-31T07:08:22.79332Z","caller":"traceutil/trace.go:171","msg":"trace[508704501] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7516; }","duration":"526.674424ms","start":"2026-08-31T07:08:22.266608Z","end":"2026-08-31T07:08:22.793282Z","steps":["trace[508704501] 'agreement among raft nodes before linearized reading' (duration: 526.230705ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.793329Z","caller":"traceutil/trace.go:171","msg":"trace[854663318] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:7516; }","duration":"147.994806ms","start":"2026-08-31T07:08:22.645317Z","end":"2026-08-31T07:08:22.793311Z","steps":["trace[854663318] 'agreement among raft nodes before linearized reading' (duration: 147.896574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.793366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.832488ms","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-31T07:08:22.793354Z","caller":"traceutil/trace.go:171","msg":"trace[277686217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7516; }","duration":"371.128337ms","start":"2026-08-31T07:08:22.422218Z","end":"2026-08-31T07:08:22.793346Z","steps":["trace[277686217] 'agreement among raft nodes before linearized reading' (duration: 370.972674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.793417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:22.422193Z","time spent":"371.21645ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-31T07:08:22.793419Z","caller":"traceutil/trace.go:171","msg":"trace[1309515716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7516; }","duration":"243.9363ms","start":"2026-08-31T07:08:22.549468Z","end":"2026-08-31T07:08:22.793404Z","steps":["trace[1309515716] 'agreement among raft nodes before linearized reading' (duration: 243.805197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.793378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:22.266592Z","time spent":"526.772977ms","remote":"127.0.0.1:45202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-08-31T07:08:22.959403Z","caller":"traceutil/trace.go:171","msg":"trace[1175226700] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"158.800538ms","start":"2026-08-31T07:08:22.800567Z","end":"2026-08-31T07:08:22.959367Z","steps":["trace[1175226700] 'process raft request' (duration: 120.091155ms)","trace[1175226700] 'compare' (duration: 38.244633ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.959656Z","caller":"traceutil/trace.go:171","msg":"trace[1344172453] linearizableReadLoop","detail":"{readStateIndex:7794; appliedIndex:7792; }","duration":"113.424251ms","start":"2026-08-31T07:08:22.846208Z","end":"2026-08-31T07:08:22.959633Z","steps":["trace[1344172453] 'read index received' (duration: 74.469872ms)","trace[1344172453] 'applied index is now lower than readState.Index' (duration: 38.952929ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:22.959802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.565644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:22.959849Z","caller":"traceutil/trace.go:171","msg":"trace[329438644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7518; }","duration":"113.671546ms","start":"2026-08-31T07:08:22.846163Z","end":"2026-08-31T07:08:22.959834Z","steps":["trace[329438644] 'agreement among raft nodes before linearized reading' (duration: 113.552404ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.959857Z","caller":"traceutil/trace.go:171","msg":"trace[1711578804] transaction","detail":"{read_only:false; response_revision:7518; number_of_response:1; }","duration":"158.759156ms","start":"2026-08-31T07:08:22.800991Z","end":"2026-08-31T07:08:22.95975Z","steps":["trace[1711578804] 'process raft request' (duration: 158.312387ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.309471Z","caller":"traceutil/trace.go:171","msg":"trace[1182324208] transaction","detail":"{read_only:false; response_revision:7519; number_of_response:1; }","duration":"114.698078ms","start":"2026-08-31T07:08:23.194753Z","end":"2026-08-31T07:08:23.309451Z","steps":["trace[1182324208] 'process raft request' (duration: 114.575725ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.309517Z","caller":"traceutil/trace.go:171","msg":"trace[2133325749] linearizableReadLoop","detail":"{readStateIndex:7795; appliedIndex:7795; }","duration":"114.703648ms","start":"2026-08-31T07:08:23.194796Z","end":"2026-08-31T07:08:23.309499Z","steps":["trace[2133325749] 'read index received' (duration: 114.693838ms)","trace[2133325749] 'applied index is now lower than readState.Index' (duration: 7.98µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:23.309873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.074456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:10684"} {"level":"info","ts":"2026-08-31T07:08:23.309908Z","caller":"traceutil/trace.go:171","msg":"trace[240426633] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7519; }","duration":"115.166848ms","start":"2026-08-31T07:08:23.194731Z","end":"2026-08-31T07:08:23.309898Z","steps":["trace[240426633] 'agreement among raft nodes before linearized reading' (duration: 114.864352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.322064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.539075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-31T07:08:23.322145Z","caller":"traceutil/trace.go:171","msg":"trace[378574045] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7521; }","duration":"107.679848ms","start":"2026-08-31T07:08:23.214433Z","end":"2026-08-31T07:08:23.322113Z","steps":["trace[378574045] 'agreement among raft nodes before linearized reading' (duration: 107.492244ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.955046Z","caller":"traceutil/trace.go:171","msg":"trace[1671957777] transaction","detail":"{read_only:false; response_revision:7527; number_of_response:1; }","duration":"102.996216ms","start":"2026-08-31T07:08:23.852024Z","end":"2026-08-31T07:08:23.95502Z","steps":["trace[1671957777] 'process raft request' (duration: 83.925926ms)","trace[1671957777] 'compare' (duration: 18.792633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:29.659409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.624086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:29.65956Z","caller":"traceutil/trace.go:171","msg":"trace[714229803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:7559; }","duration":"100.866561ms","start":"2026-08-31T07:08:29.558659Z","end":"2026-08-31T07:08:29.659525Z","steps":["trace[714229803] 'count revisions from in-memory index tree' (duration: 100.500523ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:30.015978Z","caller":"traceutil/trace.go:171","msg":"trace[1591957790] transaction","detail":"{read_only:false; response_revision:7561; number_of_response:1; }","duration":"110.355454ms","start":"2026-08-31T07:08:29.905571Z","end":"2026-08-31T07:08:30.015927Z","steps":["trace[1591957790] 'process raft request' (duration: 110.086889ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.190374Z","caller":"traceutil/trace.go:171","msg":"trace[1832735971] linearizableReadLoop","detail":"{readStateIndex:7844; appliedIndex:7843; }","duration":"126.121805ms","start":"2026-08-31T07:08:31.064228Z","end":"2026-08-31T07:08:31.19035Z","steps":["trace[1832735971] 'read index received' (duration: 125.939551ms)","trace[1832735971] 'applied index is now lower than readState.Index' (duration: 181.094µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:31.190433Z","caller":"traceutil/trace.go:171","msg":"trace[397797470] transaction","detail":"{read_only:false; response_revision:7566; number_of_response:1; }","duration":"152.086824ms","start":"2026-08-31T07:08:31.03831Z","end":"2026-08-31T07:08:31.190396Z","steps":["trace[397797470] 'process raft request' (duration: 151.861769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.190644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.888493ms","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-31T07:08:31.190691Z","caller":"traceutil/trace.go:171","msg":"trace[1734263528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7566; }","duration":"101.990965ms","start":"2026-08-31T07:08:31.088687Z","end":"2026-08-31T07:08:31.190678Z","steps":["trace[1734263528] 'agreement among raft nodes before linearized reading' (duration: 101.823551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.190687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.430611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T07:08:31.190769Z","caller":"traceutil/trace.go:171","msg":"trace[1475519018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7566; }","duration":"126.570914ms","start":"2026-08-31T07:08:31.064178Z","end":"2026-08-31T07:08:31.190749Z","steps":["trace[1475519018] 'agreement among raft nodes before linearized reading' (duration: 126.325909ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.31073Z","caller":"traceutil/trace.go:171","msg":"trace[1579656768] transaction","detail":"{read_only:false; response_revision:7567; number_of_response:1; }","duration":"115.21479ms","start":"2026-08-31T07:08:31.195472Z","end":"2026-08-31T07:08:31.310687Z","steps":["trace[1579656768] 'process raft request' (duration: 45.340746ms)","trace[1579656768] 'compare' (duration: 69.623548ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:31.310938Z","caller":"traceutil/trace.go:171","msg":"trace[518212447] transaction","detail":"{read_only:false; response_revision:7568; number_of_response:1; }","duration":"115.25338ms","start":"2026-08-31T07:08:31.195639Z","end":"2026-08-31T07:08:31.310892Z","steps":["trace[518212447] 'process raft request' (duration: 114.995104ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:32.210024Z","caller":"traceutil/trace.go:171","msg":"trace[2084419406] linearizableReadLoop","detail":"{readStateIndex:7850; appliedIndex:7849; }","duration":"101.932003ms","start":"2026-08-31T07:08:32.108049Z","end":"2026-08-31T07:08:32.209981Z","steps":["trace[2084419406] 'read index received' (duration: 41.785249ms)","trace[2084419406] 'applied index is now lower than readState.Index' (duration: 60.145524ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:32.210237Z","caller":"traceutil/trace.go:171","msg":"trace[1310502373] transaction","detail":"{read_only:false; response_revision:7572; number_of_response:1; }","duration":"106.022011ms","start":"2026-08-31T07:08:32.104164Z","end":"2026-08-31T07:08:32.210186Z","steps":["trace[1310502373] 'process raft request' (duration: 45.775995ms)","trace[1310502373] 'compare' (duration: 59.920579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:32.210526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.453334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:18 size:160819"} {"level":"info","ts":"2026-08-31T07:08:32.210583Z","caller":"traceutil/trace.go:171","msg":"trace[832813913] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:18; response_revision:7572; }","duration":"102.527006ms","start":"2026-08-31T07:08:32.108041Z","end":"2026-08-31T07:08:32.210568Z","steps":["trace[832813913] 'agreement among raft nodes before linearized reading' (duration: 102.055495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:33.844924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.116643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:33.846239Z","caller":"traceutil/trace.go:171","msg":"trace[54827697] transaction","detail":"{read_only:false; response_revision:7577; number_of_response:1; }","duration":"524.025877ms","start":"2026-08-31T07:08:33.321328Z","end":"2026-08-31T07:08:33.845354Z","steps":["trace[54827697] 'process raft request' (duration: 88.284131ms)","trace[54827697] 'compare' (duration: 434.905479ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:33.84633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:33.32129Z","time spent":"524.996458ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:33.847223Z","caller":"traceutil/trace.go:171","msg":"trace[281713430] transaction","detail":"{read_only:false; response_revision:7578; number_of_response:1; }","duration":"525.123351ms","start":"2026-08-31T07:08:33.322071Z","end":"2026-08-31T07:08:33.847195Z","steps":["trace[281713430] 'process raft request' (duration: 524.963878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:33.847337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:33.32205Z","time spent":"525.237794ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:33.877891Z","caller":"traceutil/trace.go:171","msg":"trace[1955906273] transaction","detail":"{read_only:false; response_revision:7579; number_of_response:1; }","duration":"398.114517ms","start":"2026-08-31T07:08:33.479749Z","end":"2026-08-31T07:08:33.877863Z","steps":["trace[1955906273] 'process raft request' (duration: 397.7926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:33.878054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:33.479712Z","time spent":"398.24833ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:33.878348Z","caller":"traceutil/trace.go:171","msg":"trace[347196290] transaction","detail":"{read_only:false; response_revision:7580; number_of_response:1; }","duration":"380.141831ms","start":"2026-08-31T07:08:33.498155Z","end":"2026-08-31T07:08:33.878297Z","steps":["trace[347196290] 'process raft request' (duration: 379.482077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:33.878644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:33.498134Z","time spent":"380.310955ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:08:34.091469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.680871ms","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-31T07:08:34.09166Z","caller":"traceutil/trace.go:171","msg":"trace[869466117] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7581; }","duration":"135.904155ms","start":"2026-08-31T07:08:33.955726Z","end":"2026-08-31T07:08:34.09163Z","steps":["trace[869466117] 'range keys from in-memory index tree' (duration: 135.64423ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:36.020219Z","caller":"traceutil/trace.go:171","msg":"trace[1964222963] linearizableReadLoop","detail":"{readStateIndex:7870; appliedIndex:7867; }","duration":"130.997368ms","start":"2026-08-31T07:08:35.889174Z","end":"2026-08-31T07:08:36.020172Z","steps":["trace[1964222963] 'read index received' (duration: 20.262466ms)","trace[1964222963] 'applied index is now lower than readState.Index' (duration: 110.733432ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:36.020271Z","caller":"traceutil/trace.go:171","msg":"trace[1699170993] transaction","detail":"{read_only:false; response_revision:7590; number_of_response:1; }","duration":"162.054226ms","start":"2026-08-31T07:08:35.858168Z","end":"2026-08-31T07:08:36.020223Z","steps":["trace[1699170993] 'process raft request' (duration: 95.226679ms)","trace[1699170993] 'compare' (duration: 66.509861ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:36.020291Z","caller":"traceutil/trace.go:171","msg":"trace[623030998] transaction","detail":"{read_only:false; response_revision:7591; number_of_response:1; }","duration":"133.83392ms","start":"2026-08-31T07:08:35.886414Z","end":"2026-08-31T07:08:36.020248Z","steps":["trace[623030998] 'process raft request' (duration: 133.688497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:36.020535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.664461ms","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-31T07:08:36.020576Z","caller":"traceutil/trace.go:171","msg":"trace[568152581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7591; }","duration":"103.726913ms","start":"2026-08-31T07:08:35.916837Z","end":"2026-08-31T07:08:36.020564Z","steps":["trace[568152581] 'agreement among raft nodes before linearized reading' (duration: 103.508607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:36.020846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.368898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:08:36.021224Z","caller":"traceutil/trace.go:171","msg":"trace[311159808] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:7591; }","duration":"131.861238ms","start":"2026-08-31T07:08:35.889093Z","end":"2026-08-31T07:08:36.020954Z","steps":["trace[311159808] 'agreement among raft nodes before linearized reading' (duration: 131.283615ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:09:21.301003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5619} {"level":"info","ts":"2026-08-31T07:09:21.462553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5619,"took":"159.788488ms","hash":2319601800,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":27353088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-31T07:09:21.462637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2319601800,"revision":5619,"compact-revision":2947} {"level":"info","ts":"2026-08-31T07:10:49.551379Z","caller":"traceutil/trace.go:171","msg":"trace[991275386] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"263.541749ms","start":"2026-08-31T07:10:49.287811Z","end":"2026-08-31T07:10:49.551353Z","steps":["trace[991275386] 'process raft request' (duration: 263.391386ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:10:49.681004Z","caller":"traceutil/trace.go:171","msg":"trace[150884278] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"375.821853ms","start":"2026-08-31T07:10:49.305151Z","end":"2026-08-31T07:10:49.680972Z","steps":["trace[150884278] 'process raft request' (duration: 320.816915ms)","trace[150884278] 'compare' (duration: 54.669871ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:10:49.681201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:10:49.30513Z","time spent":"375.974826ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:10:49.681204Z","caller":"traceutil/trace.go:171","msg":"trace[822667847] linearizableReadLoop","detail":"{readStateIndex:9006; appliedIndex:9003; }","duration":"254.438386ms","start":"2026-08-31T07:10:49.426719Z","end":"2026-08-31T07:10:49.681157Z","steps":["trace[822667847] 'read index received' (duration: 124.532796ms)","trace[822667847] 'applied index is now lower than readState.Index' (duration: 129.90368ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:10:49.681319Z","caller":"traceutil/trace.go:171","msg":"trace[263965227] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"263.416117ms","start":"2026-08-31T07:10:49.417846Z","end":"2026-08-31T07:10:49.681262Z","steps":["trace[263965227] 'process raft request' (duration: 263.056589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:10:49.681436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.034679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:10:49.681477Z","caller":"traceutil/trace.go:171","msg":"trace[1754095852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8693; }","duration":"206.12269ms","start":"2026-08-31T07:10:49.475341Z","end":"2026-08-31T07:10:49.681463Z","steps":["trace[1754095852] 'agreement among raft nodes before linearized reading' (duration: 206.020678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:10:49.681474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.746732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2l7sm\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-31T07:10:49.681568Z","caller":"traceutil/trace.go:171","msg":"trace[1287940500] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2l7sm; range_end:; response_count:1; response_revision:8693; }","duration":"254.878455ms","start":"2026-08-31T07:10:49.426668Z","end":"2026-08-31T07:10:49.681546Z","steps":["trace[1287940500] 'agreement among raft nodes before linearized reading' (duration: 254.65837ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:10:59.74817Z","caller":"traceutil/trace.go:171","msg":"trace[1848676405] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"149.923878ms","start":"2026-08-31T07:10:59.598221Z","end":"2026-08-31T07:10:59.748145Z","steps":["trace[1848676405] 'process raft request' (duration: 149.757495ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:00.074778Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002366.wal"} {"level":"info","ts":"2026-08-31T07:11:00.075449Z","caller":"traceutil/trace.go:171","msg":"trace[285988283] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"126.108139ms","start":"2026-08-31T07:10:59.949313Z","end":"2026-08-31T07:11:00.075421Z","steps":["trace[285988283] 'process raft request' (duration: 125.75132ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:01.378405Z","caller":"traceutil/trace.go:171","msg":"trace[1798246706] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"216.585225ms","start":"2026-08-31T07:11:01.161791Z","end":"2026-08-31T07:11:01.378376Z","steps":["trace[1798246706] 'process raft request' (duration: 194.458592ms)","trace[1798246706] 'compare' (duration: 21.807447ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:31.290244Z","caller":"traceutil/trace.go:171","msg":"trace[28140914] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"301.720806ms","start":"2026-08-31T07:11:30.988488Z","end":"2026-08-31T07:11:31.290209Z","steps":["trace[28140914] 'process raft request' (duration: 301.526012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:31.290446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:11:30.988463Z","time spent":"301.886369ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:11:31.432523Z","caller":"traceutil/trace.go:171","msg":"trace[159417423] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"138.443272ms","start":"2026-08-31T07:11:31.294043Z","end":"2026-08-31T07:11:31.432486Z","steps":["trace[159417423] 'process raft request' (duration: 82.419053ms)","trace[159417423] 'compare' (duration: 55.779124ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:31.43768Z","caller":"traceutil/trace.go:171","msg":"trace[601920700] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"142.275985ms","start":"2026-08-31T07:11:31.295389Z","end":"2026-08-31T07:11:31.437665Z","steps":["trace[601920700] 'process raft request' (duration: 142.150672ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:31.438054Z","caller":"traceutil/trace.go:171","msg":"trace[1744174580] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"135.254994ms","start":"2026-08-31T07:11:31.302736Z","end":"2026-08-31T07:11:31.437991Z","steps":["trace[1744174580] 'process raft request' (duration: 134.882566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:41.65566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.464612ms","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-31T07:11:41.655832Z","caller":"traceutil/trace.go:171","msg":"trace[736746270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9020; }","duration":"166.669326ms","start":"2026-08-31T07:11:41.489102Z","end":"2026-08-31T07:11:41.655772Z","steps":["trace[736746270] 'range keys from in-memory index tree' (duration: 166.172646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:41.655819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.007945ms","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-31T07:11:41.655943Z","caller":"traceutil/trace.go:171","msg":"trace[768749227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9020; }","duration":"147.198149ms","start":"2026-08-31T07:11:41.508725Z","end":"2026-08-31T07:11:41.655923Z","steps":["trace[768749227] 'range keys from in-memory index tree' (duration: 146.866002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:41.656022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.681161ms","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-31T07:11:41.656156Z","caller":"traceutil/trace.go:171","msg":"trace[1995114850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9020; }","duration":"141.877337ms","start":"2026-08-31T07:11:41.514251Z","end":"2026-08-31T07:11:41.656129Z","steps":["trace[1995114850] 'range keys from in-memory index tree' (duration: 141.407946ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:41.835515Z","caller":"traceutil/trace.go:171","msg":"trace[1450937578] transaction","detail":"{read_only:false; response_revision:9022; number_of_response:1; }","duration":"172.966513ms","start":"2026-08-31T07:11:41.662501Z","end":"2026-08-31T07:11:41.835467Z","steps":["trace[1450937578] 'process raft request' (duration: 118.785132ms)","trace[1450937578] 'compare' (duration: 53.860273ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:41.835722Z","caller":"traceutil/trace.go:171","msg":"trace[405261679] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"173.122146ms","start":"2026-08-31T07:11:41.662564Z","end":"2026-08-31T07:11:41.835686Z","steps":["trace[405261679] 'process raft request' (duration: 172.796079ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:41.835853Z","caller":"traceutil/trace.go:171","msg":"trace[281208273] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"172.760768ms","start":"2026-08-31T07:11:41.66308Z","end":"2026-08-31T07:11:41.835841Z","steps":["trace[281208273] 'process raft request' (duration: 172.330189ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:45.351853Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd65ba9af29ebfc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:12:45.362147Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-31T07:12:45.36245Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-31T07:12:46.361303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.217199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:46.361645Z","caller":"traceutil/trace.go:171","msg":"trace[693157330] linearizableReadLoop","detail":"{readStateIndex:10043; appliedIndex:10040; }","duration":"146.788173ms","start":"2026-08-31T07:12:46.214827Z","end":"2026-08-31T07:12:46.361615Z","steps":["trace[693157330] 'read index received' (duration: 9.695898ms)","trace[693157330] 'applied index is now lower than readState.Index' (duration: 137.090654ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:46.361753Z","caller":"traceutil/trace.go:171","msg":"trace[1501389978] transaction","detail":"{read_only:false; response_revision:9697; number_of_response:1; }","duration":"145.71535ms","start":"2026-08-31T07:12:46.215994Z","end":"2026-08-31T07:12:46.36171Z","steps":["trace[1501389978] 'process raft request' (duration: 145.489785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:46.361925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.087679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-08-31T07:12:46.361992Z","caller":"traceutil/trace.go:171","msg":"trace[974560611] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:9697; }","duration":"147.18228ms","start":"2026-08-31T07:12:46.214786Z","end":"2026-08-31T07:12:46.361969Z","steps":["trace[974560611] 'agreement among raft nodes before linearized reading' (duration: 146.917394ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:46.362003Z","caller":"traceutil/trace.go:171","msg":"trace[378917425] transaction","detail":"{read_only:false; response_revision:9696; number_of_response:1; }","duration":"149.554102ms","start":"2026-08-31T07:12:46.212434Z","end":"2026-08-31T07:12:46.361988Z","steps":["trace[378917425] 'process raft request' (duration: 30.479765ms)","trace[378917425] 'compare' (duration: 117.84887ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:46.396069Z","caller":"traceutil/trace.go:171","msg":"trace[1730887692] transaction","detail":"{read_only:false; response_revision:9698; number_of_response:1; }","duration":"167.626561ms","start":"2026-08-31T07:12:46.228412Z","end":"2026-08-31T07:12:46.396039Z","steps":["trace[1730887692] 'process raft request' (duration: 167.378465ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:46.396185Z","caller":"traceutil/trace.go:171","msg":"trace[432037090] transaction","detail":"{read_only:false; response_revision:9700; number_of_response:1; }","duration":"154.664263ms","start":"2026-08-31T07:12:46.24149Z","end":"2026-08-31T07:12:46.396154Z","steps":["trace[432037090] 'process raft request' (duration: 154.554141ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:46.396334Z","caller":"traceutil/trace.go:171","msg":"trace[2051183993] transaction","detail":"{read_only:false; response_revision:9699; number_of_response:1; }","duration":"162.084791ms","start":"2026-08-31T07:12:46.234212Z","end":"2026-08-31T07:12:46.396297Z","steps":["trace[2051183993] 'process raft request' (duration: 161.732414ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:47.044312Z","caller":"traceutil/trace.go:171","msg":"trace[2012542104] transaction","detail":"{read_only:false; response_revision:9710; number_of_response:1; }","duration":"102.678476ms","start":"2026-08-31T07:12:46.941593Z","end":"2026-08-31T07:12:47.044272Z","steps":["trace[2012542104] 'process raft request' (duration: 80.351116ms)","trace[2012542104] 'compare' (duration: 22.135355ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:55.496798Z","caller":"traceutil/trace.go:171","msg":"trace[1980738353] transaction","detail":"{read_only:false; response_revision:9784; number_of_response:1; }","duration":"123.612225ms","start":"2026-08-31T07:12:55.373161Z","end":"2026-08-31T07:12:55.496773Z","steps":["trace[1980738353] 'process raft request' (duration: 123.533493ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:55.496847Z","caller":"traceutil/trace.go:171","msg":"trace[578009737] transaction","detail":"{read_only:false; response_revision:9783; number_of_response:1; }","duration":"126.743262ms","start":"2026-08-31T07:12:55.370064Z","end":"2026-08-31T07:12:55.496808Z","steps":["trace[578009737] 'process raft request' (duration: 126.515837ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:58.28678Z","caller":"traceutil/trace.go:171","msg":"trace[127137168] transaction","detail":"{read_only:false; response_revision:9805; number_of_response:1; }","duration":"111.152377ms","start":"2026-08-31T07:12:58.175601Z","end":"2026-08-31T07:12:58.286753Z","steps":["trace[127137168] 'process raft request' (duration: 110.961773ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:58.621601Z","caller":"traceutil/trace.go:171","msg":"trace[2082118219] linearizableReadLoop","detail":"{readStateIndex:10158; appliedIndex:10156; }","duration":"151.396901ms","start":"2026-08-31T07:12:58.470182Z","end":"2026-08-31T07:12:58.621579Z","steps":["trace[2082118219] 'read index received' (duration: 151.115636ms)","trace[2082118219] 'applied index is now lower than readState.Index' (duration: 280.095µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:58.621657Z","caller":"traceutil/trace.go:171","msg":"trace[2051768981] transaction","detail":"{read_only:false; response_revision:9808; number_of_response:1; }","duration":"160.622511ms","start":"2026-08-31T07:12:58.46102Z","end":"2026-08-31T07:12:58.621642Z","steps":["trace[2051768981] 'process raft request' (duration: 160.343715ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:58.621736Z","caller":"traceutil/trace.go:171","msg":"trace[1298164420] transaction","detail":"{read_only:false; response_revision:9809; number_of_response:1; }","duration":"159.347562ms","start":"2026-08-31T07:12:58.462373Z","end":"2026-08-31T07:12:58.62172Z","steps":["trace[1298164420] 'process raft request' (duration: 159.150038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.622019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.83474ms","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-31T07:12:58.62205Z","caller":"traceutil/trace.go:171","msg":"trace[1349989777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9809; }","duration":"151.910762ms","start":"2026-08-31T07:12:58.470131Z","end":"2026-08-31T07:12:58.622042Z","steps":["trace[1349989777] 'agreement among raft nodes before linearized reading' (duration: 151.81421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.622246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.322146ms","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-31T07:12:58.62232Z","caller":"traceutil/trace.go:171","msg":"trace[1599377766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9809; }","duration":"149.47194ms","start":"2026-08-31T07:12:58.47283Z","end":"2026-08-31T07:12:58.622302Z","steps":["trace[1599377766] 'agreement among raft nodes before linearized reading' (duration: 149.280346ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:59.12081Z","caller":"traceutil/trace.go:171","msg":"trace[221751976] transaction","detail":"{read_only:false; response_revision:9814; number_of_response:1; }","duration":"100.812736ms","start":"2026-08-31T07:12:59.019965Z","end":"2026-08-31T07:12:59.120778Z","steps":["trace[221751976] 'process raft request' (duration: 78.751212ms)","trace[221751976] 'compare' (duration: 21.824269ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:00.579102Z","caller":"traceutil/trace.go:171","msg":"trace[575561253] transaction","detail":"{read_only:false; response_revision:9820; number_of_response:1; }","duration":"101.94318ms","start":"2026-08-31T07:13:00.477119Z","end":"2026-08-31T07:13:00.579062Z","steps":["trace[575561253] 'process raft request' (duration: 101.50826ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:02.936515Z","caller":"traceutil/trace.go:171","msg":"trace[655510880] transaction","detail":"{read_only:false; response_revision:9830; number_of_response:1; }","duration":"346.535583ms","start":"2026-08-31T07:13:02.589956Z","end":"2026-08-31T07:13:02.936491Z","steps":["trace[655510880] 'process raft request' (duration: 346.3959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:02.936669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:02.589927Z","time spent":"346.663516ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:13:02.973282Z","caller":"traceutil/trace.go:171","msg":"trace[856822124] transaction","detail":"{read_only:false; response_revision:9832; number_of_response:1; }","duration":"334.696648ms","start":"2026-08-31T07:13:02.638561Z","end":"2026-08-31T07:13:02.973258Z","steps":["trace[856822124] 'process raft request' (duration: 334.614846ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:02.973334Z","caller":"traceutil/trace.go:171","msg":"trace[1415702859] transaction","detail":"{read_only:false; response_revision:9831; number_of_response:1; }","duration":"336.686411ms","start":"2026-08-31T07:13:02.636622Z","end":"2026-08-31T07:13:02.973308Z","steps":["trace[1415702859] 'process raft request' (duration: 309.738123ms)","trace[1415702859] 'compare' (duration: 26.706573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:13:02.973411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:02.638548Z","time spent":"334.7936ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:13:02.973428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:02.636586Z","time spent":"336.792633ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:13:02.973659Z","caller":"traceutil/trace.go:171","msg":"trace[555874628] linearizableReadLoop","detail":"{readStateIndex:10182; appliedIndex:10180; }","duration":"268.856854ms","start":"2026-08-31T07:13:02.704703Z","end":"2026-08-31T07:13:02.97356Z","steps":["trace[555874628] 'read index received' (duration: 232.235338ms)","trace[555874628] 'applied index is now lower than readState.Index' (duration: 36.617886ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:02.973782Z","caller":"traceutil/trace.go:171","msg":"trace[638002193] transaction","detail":"{read_only:false; response_revision:9833; number_of_response:1; }","duration":"154.068139ms","start":"2026-08-31T07:13:02.819664Z","end":"2026-08-31T07:13:02.973732Z","steps":["trace[638002193] 'process raft request' (duration: 153.561898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:02.973939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.240342ms","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-31T07:13:02.973992Z","caller":"traceutil/trace.go:171","msg":"trace[1784383316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9833; }","duration":"269.337584ms","start":"2026-08-31T07:13:02.70464Z","end":"2026-08-31T07:13:02.973978Z","steps":["trace[1784383316] 'agreement among raft nodes before linearized reading' (duration: 269.16264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:02.974143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.220231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2l7sm\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-31T07:13:02.974173Z","caller":"traceutil/trace.go:171","msg":"trace[1739252230] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2l7sm; range_end:; response_count:1; response_revision:9833; }","duration":"248.319233ms","start":"2026-08-31T07:13:02.725846Z","end":"2026-08-31T07:13:02.974166Z","steps":["trace[1739252230] 'agreement among raft nodes before linearized reading' (duration: 248.23301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:02.974244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.050683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-31T07:13:02.974394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.745905ms","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-31T07:13:02.974399Z","caller":"traceutil/trace.go:171","msg":"trace[231276639] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:9833; }","duration":"245.239907ms","start":"2026-08-31T07:13:02.729138Z","end":"2026-08-31T07:13:02.974378Z","steps":["trace[231276639] 'agreement among raft nodes before linearized reading' (duration: 244.9202ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:02.974516Z","caller":"traceutil/trace.go:171","msg":"trace[1613295731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9833; }","duration":"263.911819ms","start":"2026-08-31T07:13:02.710583Z","end":"2026-08-31T07:13:02.974495Z","steps":["trace[1613295731] 'agreement among raft nodes before linearized reading' (duration: 263.439358ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:17.990991Z","caller":"traceutil/trace.go:171","msg":"trace[1777348116] transaction","detail":"{read_only:false; response_revision:9917; number_of_response:1; }","duration":"107.063409ms","start":"2026-08-31T07:13:17.883587Z","end":"2026-08-31T07:13:17.990651Z","steps":["trace[1777348116] 'process raft request' (duration: 106.732762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:18.358534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.838862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:13:18.358633Z","caller":"traceutil/trace.go:171","msg":"trace[1465681207] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:9917; }","duration":"260.987095ms","start":"2026-08-31T07:13:18.097625Z","end":"2026-08-31T07:13:18.358613Z","steps":["trace[1465681207] 'count revisions from in-memory index tree' (duration: 260.77361ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:18.359403Z","caller":"traceutil/trace.go:171","msg":"trace[345126562] transaction","detail":"{read_only:false; response_revision:9918; number_of_response:1; }","duration":"191.420092ms","start":"2026-08-31T07:13:18.167891Z","end":"2026-08-31T07:13:18.359311Z","steps":["trace[345126562] 'process raft request' (duration: 177.169615ms)","trace[345126562] 'compare' (duration: 13.152692ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:13:18.751176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.384929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:13:18.751294Z","caller":"traceutil/trace.go:171","msg":"trace[1205433841] linearizableReadLoop","detail":"{readStateIndex:10272; appliedIndex:10271; }","duration":"313.362899ms","start":"2026-08-31T07:13:18.437912Z","end":"2026-08-31T07:13:18.751275Z","steps":["trace[1205433841] 'read index received' (duration: 176.690904ms)","trace[1205433841] 'applied index is now lower than readState.Index' (duration: 136.670635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:13:18.751956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.698288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-31T07:13:18.752052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.147407ms","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-31T07:13:18.752099Z","caller":"traceutil/trace.go:171","msg":"trace[1184923858] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9919; }","duration":"246.924303ms","start":"2026-08-31T07:13:18.505149Z","end":"2026-08-31T07:13:18.752074Z","steps":["trace[1184923858] 'agreement among raft nodes before linearized reading' (duration: 246.495654ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:18.751973Z","caller":"traceutil/trace.go:171","msg":"trace[974961203] transaction","detail":"{read_only:false; response_revision:9919; number_of_response:1; }","duration":"333.015963ms","start":"2026-08-31T07:13:18.4189Z","end":"2026-08-31T07:13:18.751916Z","steps":["trace[974961203] 'process raft request' (duration: 195.752494ms)","trace[974961203] 'compare' (duration: 136.067092ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:18.752289Z","caller":"traceutil/trace.go:171","msg":"trace[628428980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9919; }","duration":"314.287279ms","start":"2026-08-31T07:13:18.437805Z","end":"2026-08-31T07:13:18.752092Z","steps":["trace[628428980] 'agreement among raft nodes before linearized reading' (duration: 314.098045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:18.752384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:18.437764Z","time spent":"314.601236ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T07:13:18.752431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:18.418881Z","time spent":"333.433801ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:13:19.575278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.585661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:13:19.575676Z","caller":"traceutil/trace.go:171","msg":"trace[2097253601] linearizableReadLoop","detail":"{readStateIndex:10279; appliedIndex:10277; }","duration":"409.900604ms","start":"2026-08-31T07:13:19.165748Z","end":"2026-08-31T07:13:19.575649Z","steps":["trace[2097253601] 'read index received' (duration: 2.706818ms)","trace[2097253601] 'applied index is now lower than readState.Index' (duration: 407.191526ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:19.57571Z","caller":"traceutil/trace.go:171","msg":"trace[378051943] transaction","detail":"{read_only:false; response_revision:9926; number_of_response:1; }","duration":"412.196442ms","start":"2026-08-31T07:13:19.163471Z","end":"2026-08-31T07:13:19.575667Z","steps":["trace[378051943] 'process raft request' (duration: 182.126281ms)","trace[378051943] 'compare' (duration: 229.435388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:13:19.57595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:19.163453Z","time spent":"412.352166ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:13:19.576357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.600009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:289856"} {"level":"info","ts":"2026-08-31T07:13:19.576423Z","caller":"traceutil/trace.go:171","msg":"trace[1395443916] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9926; }","duration":"410.717161ms","start":"2026-08-31T07:13:19.165693Z","end":"2026-08-31T07:13:19.57641Z","steps":["trace[1395443916] 'agreement among raft nodes before linearized reading' (duration: 410.127739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:19.576466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:19.165673Z","time spent":"410.781313ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":289879,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T07:13:19.583973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.802129ms","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-31T07:13:19.584047Z","caller":"traceutil/trace.go:171","msg":"trace[75553138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:9927; }","duration":"360.946192ms","start":"2026-08-31T07:13:19.223082Z","end":"2026-08-31T07:13:19.584028Z","steps":["trace[75553138] 'agreement among raft nodes before linearized reading' (duration: 360.798029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:19.584108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:19.223052Z","time spent":"361.028464ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-08-31T07:14:21.310718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7992} {"level":"info","ts":"2026-08-31T07:14:21.407633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7992,"took":"93.097769ms","hash":3996048071,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25051136,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-31T07:14:21.407719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996048071,"revision":7992,"compact-revision":5619} {"level":"warn","ts":"2026-08-31T07:18:53.090288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.846011ms","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-31T07:18:53.09035Z","caller":"traceutil/trace.go:171","msg":"trace[2041625596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12328; }","duration":"229.939494ms","start":"2026-08-31T07:18:52.860398Z","end":"2026-08-31T07:18:53.090337Z","steps":["trace[2041625596] 'range keys from in-memory index tree' (duration: 229.713769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:53.090594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.034358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:18:53.090611Z","caller":"traceutil/trace.go:171","msg":"trace[737028371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:12328; }","duration":"156.10432ms","start":"2026-08-31T07:18:52.934501Z","end":"2026-08-31T07:18:53.090605Z","steps":["trace[737028371] 'count revisions from in-memory index tree' (duration: 155.904755ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:21.318392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10362} {"level":"info","ts":"2026-08-31T07:19:21.403098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10362,"took":"82.309397ms","hash":2826923045,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":26255360,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:19:21.403164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2826923045,"revision":10362,"compact-revision":7992} {"level":"info","ts":"2026-08-31T07:24:21.327166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12531} {"level":"info","ts":"2026-08-31T07:24:21.41199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12531,"took":"82.06891ms","hash":480189900,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":28090368,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:24:21.412056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":480189900,"revision":12531,"compact-revision":10362} {"level":"info","ts":"2026-08-31T07:26:04.32707Z","caller":"traceutil/trace.go:171","msg":"trace[1416937425] transaction","detail":"{read_only:false; response_revision:15156; number_of_response:1; }","duration":"111.960945ms","start":"2026-08-31T07:26:04.215066Z","end":"2026-08-31T07:26:04.327027Z","steps":["trace[1416937425] 'process raft request' (duration: 50.588437ms)","trace[1416937425] 'compare' (duration: 61.060632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:26:05.192383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.927405ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-31T07:26:05.192591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:26:04.831687Z","time spent":"360.894173ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-31T07:26:05.192701Z","caller":"traceutil/trace.go:171","msg":"trace[2068175370] transaction","detail":"{read_only:false; response_revision:15161; number_of_response:1; }","duration":"170.36995ms","start":"2026-08-31T07:26:05.022311Z","end":"2026-08-31T07:26:05.192681Z","steps":["trace[2068175370] 'process raft request' (duration: 170.193756ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:05.192765Z","caller":"traceutil/trace.go:171","msg":"trace[1427025233] linearizableReadLoop","detail":"{readStateIndex:15695; appliedIndex:15694; }","duration":"347.241349ms","start":"2026-08-31T07:26:04.84549Z","end":"2026-08-31T07:26:05.192731Z","steps":["trace[1427025233] 'read index received' (duration: 173.857955ms)","trace[1427025233] 'applied index is now lower than readState.Index' (duration: 173.379774ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:26:05.193033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.527356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:26:05.193098Z","caller":"traceutil/trace.go:171","msg":"trace[10735224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15161; }","duration":"347.649499ms","start":"2026-08-31T07:26:04.845433Z","end":"2026-08-31T07:26:05.193083Z","steps":["trace[10735224] 'agreement among raft nodes before linearized reading' (duration: 347.520756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:05.193114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.352797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7911"} {"level":"warn","ts":"2026-08-31T07:26:05.19314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:26:04.845411Z","time spent":"347.71858ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-31T07:26:05.193174Z","caller":"traceutil/trace.go:171","msg":"trace[267196414] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:15161; }","duration":"249.460209ms","start":"2026-08-31T07:26:04.943697Z","end":"2026-08-31T07:26:05.193157Z","steps":["trace[267196414] 'agreement among raft nodes before linearized reading' (duration: 249.274155ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:06.910452Z","caller":"traceutil/trace.go:171","msg":"trace[1334090444] transaction","detail":"{read_only:false; response_revision:15179; number_of_response:1; }","duration":"105.66073ms","start":"2026-08-31T07:26:06.804751Z","end":"2026-08-31T07:26:06.910412Z","steps":["trace[1334090444] 'process raft request' (duration: 105.454256ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:15.467307Z","caller":"traceutil/trace.go:171","msg":"trace[516941543] transaction","detail":"{read_only:false; response_revision:15333; number_of_response:1; }","duration":"292.703547ms","start":"2026-08-31T07:26:15.174571Z","end":"2026-08-31T07:26:15.467274Z","steps":["trace[516941543] 'process raft request' (duration: 292.519363ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:15.488669Z","caller":"traceutil/trace.go:171","msg":"trace[599785040] transaction","detail":"{read_only:false; response_revision:15334; number_of_response:1; }","duration":"225.367421ms","start":"2026-08-31T07:26:15.263277Z","end":"2026-08-31T07:26:15.488645Z","steps":["trace[599785040] 'process raft request' (duration: 225.147487ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:15.681533Z","caller":"traceutil/trace.go:171","msg":"trace[1590724096] linearizableReadLoop","detail":"{readStateIndex:15874; appliedIndex:15873; }","duration":"165.584626ms","start":"2026-08-31T07:26:15.515919Z","end":"2026-08-31T07:26:15.681504Z","steps":["trace[1590724096] 'read index received' (duration: 139.385214ms)","trace[1590724096] 'applied index is now lower than readState.Index' (duration: 26.197642ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:26:15.681672Z","caller":"traceutil/trace.go:171","msg":"trace[1233117601] transaction","detail":"{read_only:false; response_revision:15336; number_of_response:1; }","duration":"186.930476ms","start":"2026-08-31T07:26:15.494723Z","end":"2026-08-31T07:26:15.681654Z","steps":["trace[1233117601] 'process raft request' (duration: 160.601161ms)","trace[1233117601] 'compare' (duration: 25.65549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:26:15.682567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.628348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-sb-0.18d0d31d187023ff\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-08-31T07:26:15.682671Z","caller":"traceutil/trace.go:171","msg":"trace[1353110037] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-sb-0.18d0d31d187023ff; range_end:; response_count:1; response_revision:15336; }","duration":"166.781332ms","start":"2026-08-31T07:26:15.51587Z","end":"2026-08-31T07:26:15.682651Z","steps":["trace[1353110037] 'agreement among raft nodes before linearized reading' (duration: 165.860712ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:27:56.459076Z","caller":"traceutil/trace.go:171","msg":"trace[178659098] linearizableReadLoop","detail":"{readStateIndex:17001; appliedIndex:16998; }","duration":"131.518665ms","start":"2026-08-31T07:27:56.327534Z","end":"2026-08-31T07:27:56.459052Z","steps":["trace[178659098] 'read index received' (duration: 41.193515ms)","trace[178659098] 'applied index is now lower than readState.Index' (duration: 90.32422ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:27:56.459483Z","caller":"traceutil/trace.go:171","msg":"trace[884223796] transaction","detail":"{read_only:false; response_revision:16428; number_of_response:1; }","duration":"133.150989ms","start":"2026-08-31T07:27:56.3263Z","end":"2026-08-31T07:27:56.459451Z","steps":["trace[884223796] 'process raft request' (duration: 117.189547ms)","trace[884223796] 'compare' (duration: 15.348909ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:27:56.459634Z","caller":"traceutil/trace.go:171","msg":"trace[1484048941] transaction","detail":"{read_only:false; response_revision:16429; number_of_response:1; }","duration":"133.199281ms","start":"2026-08-31T07:27:56.326413Z","end":"2026-08-31T07:27:56.459612Z","steps":["trace[1484048941] 'process raft request' (duration: 132.595338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:27:56.459907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.351953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-31T07:27:56.459957Z","caller":"traceutil/trace.go:171","msg":"trace[956762172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16429; }","duration":"132.456976ms","start":"2026-08-31T07:27:56.327488Z","end":"2026-08-31T07:27:56.459945Z","steps":["trace[956762172] 'agreement among raft nodes before linearized reading' (duration: 132.272942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:27:56.459868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.841396ms","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-31T07:27:56.460093Z","caller":"traceutil/trace.go:171","msg":"trace[1937185506] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16429; }","duration":"107.151673ms","start":"2026-08-31T07:27:56.352932Z","end":"2026-08-31T07:27:56.460084Z","steps":["trace[1937185506] 'agreement among raft nodes before linearized reading' (duration: 106.695442ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:27:56.662374Z","caller":"traceutil/trace.go:171","msg":"trace[1832519881] linearizableReadLoop","detail":"{readStateIndex:17002; appliedIndex:17001; }","duration":"167.690672ms","start":"2026-08-31T07:27:56.494663Z","end":"2026-08-31T07:27:56.662353Z","steps":["trace[1832519881] 'read index received' (duration: 112.435485ms)","trace[1832519881] 'applied index is now lower than readState.Index' (duration: 55.254507ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:27:56.66255Z","caller":"traceutil/trace.go:171","msg":"trace[1340454129] transaction","detail":"{read_only:false; response_revision:16430; number_of_response:1; }","duration":"198.157387ms","start":"2026-08-31T07:27:56.464384Z","end":"2026-08-31T07:27:56.662541Z","steps":["trace[1340454129] 'process raft request' (duration: 142.872909ms)","trace[1340454129] 'compare' (duration: 55.016593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:27:56.662834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.391314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-08-31T07:27:56.662983Z","caller":"traceutil/trace.go:171","msg":"trace[413304565] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16430; }","duration":"146.585058ms","start":"2026-08-31T07:27:56.516374Z","end":"2026-08-31T07:27:56.662959Z","steps":["trace[413304565] 'agreement among raft nodes before linearized reading' (duration: 146.266861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:27:56.662888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.179312ms","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-31T07:27:56.663099Z","caller":"traceutil/trace.go:171","msg":"trace[947511531] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16430; }","duration":"168.464029ms","start":"2026-08-31T07:27:56.494611Z","end":"2026-08-31T07:27:56.663075Z","steps":["trace[947511531] 'agreement among raft nodes before linearized reading' (duration: 168.130112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:02.845558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.127123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-31T07:28:02.845625Z","caller":"traceutil/trace.go:171","msg":"trace[1987290867] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16459; }","duration":"162.370359ms","start":"2026-08-31T07:28:02.683243Z","end":"2026-08-31T07:28:02.845613Z","steps":["trace[1987290867] 'range keys from in-memory index tree' (duration: 161.933709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:02.845743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.777863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T07:28:02.845743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.932156ms","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-31T07:28:02.84582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.576774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-31T07:28:02.845789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.423718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-31T07:28:02.845918Z","caller":"traceutil/trace.go:171","msg":"trace[2110982192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16459; }","duration":"135.601953ms","start":"2026-08-31T07:28:02.710295Z","end":"2026-08-31T07:28:02.845897Z","steps":["trace[2110982192] 'range keys from in-memory index tree' (duration: 135.189164ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:02.846032Z","caller":"traceutil/trace.go:171","msg":"trace[2046353593] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16459; }","duration":"161.08871ms","start":"2026-08-31T07:28:02.68474Z","end":"2026-08-31T07:28:02.845829Z","steps":["trace[2046353593] 'range keys from in-memory index tree' (duration: 160.735893ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:02.845916Z","caller":"traceutil/trace.go:171","msg":"trace[608370872] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16459; }","duration":"160.916847ms","start":"2026-08-31T07:28:02.684889Z","end":"2026-08-31T07:28:02.845805Z","steps":["trace[608370872] 'range keys from in-memory index tree' (duration: 160.707472ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:02.846183Z","caller":"traceutil/trace.go:171","msg":"trace[831281077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16459; }","duration":"156.712306ms","start":"2026-08-31T07:28:02.689176Z","end":"2026-08-31T07:28:02.845888Z","steps":["trace[831281077] 'range keys from in-memory index tree' (duration: 156.39927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:02.846087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.843409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:28:02.846378Z","caller":"traceutil/trace.go:171","msg":"trace[1438595348] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16459; }","duration":"163.148505ms","start":"2026-08-31T07:28:02.683151Z","end":"2026-08-31T07:28:02.8463Z","steps":["trace[1438595348] 'range keys from in-memory index tree' (duration: 162.625144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:02.845573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.494707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-08-31T07:28:02.850125Z","caller":"traceutil/trace.go:171","msg":"trace[1068424934] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16459; }","duration":"165.056886ms","start":"2026-08-31T07:28:02.685042Z","end":"2026-08-31T07:28:02.850099Z","steps":["trace[1068424934] 'range keys from in-memory index tree' (duration: 160.357515ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:13.229509Z","caller":"traceutil/trace.go:171","msg":"trace[2105405549] transaction","detail":"{read_only:false; response_revision:16517; number_of_response:1; }","duration":"163.891741ms","start":"2026-08-31T07:28:13.065543Z","end":"2026-08-31T07:28:13.229435Z","steps":["trace[2105405549] 'process raft request' (duration: 88.839688ms)","trace[2105405549] 'compare' (duration: 74.786577ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:13.229534Z","caller":"traceutil/trace.go:171","msg":"trace[285085719] transaction","detail":"{read_only:false; response_revision:16518; number_of_response:1; }","duration":"152.17513ms","start":"2026-08-31T07:28:13.077295Z","end":"2026-08-31T07:28:13.22947Z","steps":["trace[285085719] 'process raft request' (duration: 152.044307ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:21.336766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14536} {"level":"info","ts":"2026-08-31T07:29:21.423227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14536,"took":"83.442202ms","hash":3883454475,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T07:29:21.4233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883454475,"revision":14536,"compact-revision":12531} {"level":"info","ts":"2026-08-31T07:31:03.902048Z","caller":"traceutil/trace.go:171","msg":"trace[1603830184] transaction","detail":"{read_only:false; response_revision:17895; number_of_response:1; }","duration":"111.220879ms","start":"2026-08-31T07:31:03.790794Z","end":"2026-08-31T07:31:03.902015Z","steps":["trace[1603830184] 'process raft request' (duration: 111.029785ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:31:04.02475Z","caller":"traceutil/trace.go:171","msg":"trace[673311896] transaction","detail":"{read_only:false; response_revision:17896; number_of_response:1; }","duration":"129.964911ms","start":"2026-08-31T07:31:03.894766Z","end":"2026-08-31T07:31:04.024731Z","steps":["trace[673311896] 'process raft request' (duration: 91.625828ms)","trace[673311896] 'compare' (duration: 37.968706ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:31:04.024823Z","caller":"traceutil/trace.go:171","msg":"trace[2016362342] linearizableReadLoop","detail":"{readStateIndex:18515; appliedIndex:18514; }","duration":"121.450039ms","start":"2026-08-31T07:31:03.90335Z","end":"2026-08-31T07:31:04.0248Z","steps":["trace[2016362342] 'read index received' (duration: 83.059985ms)","trace[2016362342] 'applied index is now lower than readState.Index' (duration: 38.387124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:31:04.025068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.704074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:31:04.025144Z","caller":"traceutil/trace.go:171","msg":"trace[1529682098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17897; }","duration":"121.806826ms","start":"2026-08-31T07:31:03.903303Z","end":"2026-08-31T07:31:04.02511Z","steps":["trace[1529682098] 'agreement among raft nodes before linearized reading' (duration: 121.706914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:31.90998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.123331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T07:33:31.910393Z","caller":"traceutil/trace.go:171","msg":"trace[2015742008] linearizableReadLoop","detail":"{readStateIndex:19513; appliedIndex:19512; }","duration":"259.526916ms","start":"2026-08-31T07:33:31.650845Z","end":"2026-08-31T07:33:31.910372Z","steps":["trace[2015742008] 'read index received' (duration: 30.71935ms)","trace[2015742008] 'applied index is now lower than readState.Index' (duration: 228.806196ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:33:31.911125Z","caller":"traceutil/trace.go:171","msg":"trace[2041067994] transaction","detail":"{read_only:false; response_revision:18861; number_of_response:1; }","duration":"261.323223ms","start":"2026-08-31T07:33:31.649777Z","end":"2026-08-31T07:33:31.9111Z","steps":["trace[2041067994] 'process raft request' (duration: 31.863274ms)","trace[2041067994] 'compare' (duration: 227.921366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:33:31.911795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.908315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112026"} {"level":"info","ts":"2026-08-31T07:33:31.914937Z","caller":"traceutil/trace.go:171","msg":"trace[320652059] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:18861; }","duration":"261.013207ms","start":"2026-08-31T07:33:31.650822Z","end":"2026-08-31T07:33:31.911835Z","steps":["trace[320652059] 'agreement among raft nodes before linearized reading' (duration: 260.382353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:31.917641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.454524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-08-31T07:33:31.917741Z","caller":"traceutil/trace.go:171","msg":"trace[877249616] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18861; }","duration":"239.596537ms","start":"2026-08-31T07:33:31.678116Z","end":"2026-08-31T07:33:31.917713Z","steps":["trace[877249616] 'agreement among raft nodes before linearized reading' (duration: 239.321891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:31.917642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.137071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-npl5c\" ","response":"range_response_count:1 size:22015"} {"level":"warn","ts":"2026-08-31T07:33:31.917586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.141196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T07:33:31.918121Z","caller":"traceutil/trace.go:171","msg":"trace[413785792] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:18861; }","duration":"178.7464ms","start":"2026-08-31T07:33:31.739343Z","end":"2026-08-31T07:33:31.918089Z","steps":["trace[413785792] 'agreement among raft nodes before linearized reading' (duration: 178.051904ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:31.917969Z","caller":"traceutil/trace.go:171","msg":"trace[1023383023] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-npl5c; range_end:; response_count:1; response_revision:18861; }","duration":"154.475529ms","start":"2026-08-31T07:33:31.763454Z","end":"2026-08-31T07:33:31.917929Z","steps":["trace[1023383023] 'agreement among raft nodes before linearized reading' (duration: 154.010289ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:38.73075Z","caller":"traceutil/trace.go:171","msg":"trace[1113243222] transaction","detail":"{read_only:false; response_revision:18930; number_of_response:1; }","duration":"206.042043ms","start":"2026-08-31T07:33:38.524674Z","end":"2026-08-31T07:33:38.730716Z","steps":["trace[1113243222] 'process raft request' (duration: 136.604856ms)","trace[1113243222] 'compare' (duration: 69.300674ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:33:38.743746Z","caller":"traceutil/trace.go:171","msg":"trace[1343768889] transaction","detail":"{read_only:false; response_revision:18931; number_of_response:1; }","duration":"125.58446ms","start":"2026-08-31T07:33:38.618104Z","end":"2026-08-31T07:33:38.743689Z","steps":["trace[1343768889] 'process raft request' (duration: 125.250583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:39.085393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.064151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:33:39.085459Z","caller":"traceutil/trace.go:171","msg":"trace[933562435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18932; }","duration":"239.182663ms","start":"2026-08-31T07:33:38.84626Z","end":"2026-08-31T07:33:39.085442Z","steps":["trace[933562435] 'range keys from in-memory index tree' (duration: 238.966578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:39.085671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.573409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-31T07:33:39.085796Z","caller":"traceutil/trace.go:171","msg":"trace[739155926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18932; }","duration":"103.740352ms","start":"2026-08-31T07:33:38.982018Z","end":"2026-08-31T07:33:39.085758Z","steps":["trace[739155926] 'range keys from in-memory index tree' (duration: 103.348114ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:39.22955Z","caller":"traceutil/trace.go:171","msg":"trace[1249871145] transaction","detail":"{read_only:false; response_revision:18933; number_of_response:1; }","duration":"139.552509ms","start":"2026-08-31T07:33:39.089974Z","end":"2026-08-31T07:33:39.229527Z","steps":["trace[1249871145] 'process raft request' (duration: 139.404745ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:39.250541Z","caller":"traceutil/trace.go:171","msg":"trace[509004867] linearizableReadLoop","detail":"{readStateIndex:19588; appliedIndex:19586; }","duration":"118.028118ms","start":"2026-08-31T07:33:39.132479Z","end":"2026-08-31T07:33:39.250507Z","steps":["trace[509004867] 'read index received' (duration: 96.971946ms)","trace[509004867] 'applied index is now lower than readState.Index' (duration: 21.054932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:33:39.25084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.328694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6x695\" ","response":"range_response_count:1 size:20661"} {"level":"info","ts":"2026-08-31T07:33:39.250881Z","caller":"traceutil/trace.go:171","msg":"trace[1608977882] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6x695; range_end:; response_count:1; response_revision:18934; }","duration":"118.440226ms","start":"2026-08-31T07:33:39.132427Z","end":"2026-08-31T07:33:39.250867Z","steps":["trace[1608977882] 'agreement among raft nodes before linearized reading' (duration: 118.224192ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:39.251143Z","caller":"traceutil/trace.go:171","msg":"trace[1114942244] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"160.469727ms","start":"2026-08-31T07:33:39.090646Z","end":"2026-08-31T07:33:39.251116Z","steps":["trace[1114942244] 'process raft request' (duration: 159.674051ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:42.869272Z","caller":"traceutil/trace.go:171","msg":"trace[948786018] transaction","detail":"{read_only:false; response_revision:18967; number_of_response:1; }","duration":"108.280969ms","start":"2026-08-31T07:33:42.760964Z","end":"2026-08-31T07:33:42.869244Z","steps":["trace[948786018] 'process raft request' (duration: 103.433155ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:42.869529Z","caller":"traceutil/trace.go:171","msg":"trace[2133130016] transaction","detail":"{read_only:false; response_revision:18968; number_of_response:1; }","duration":"108.31231ms","start":"2026-08-31T07:33:42.761169Z","end":"2026-08-31T07:33:42.869481Z","steps":["trace[2133130016] 'process raft request' (duration: 108.024294ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:19.042757Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd65ba9af29ebfc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:34:19.050243Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-31T07:34:19.050573Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-31T07:34:21.371683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16966} {"level":"info","ts":"2026-08-31T07:34:21.593152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16966,"took":"218.578742ms","hash":3159819798,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":32903168,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-31T07:34:21.593205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3159819798,"revision":16966,"compact-revision":14536} {"level":"info","ts":"2026-08-31T07:39:21.391432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19356} {"level":"info","ts":"2026-08-31T07:39:21.533719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19356,"took":"136.678978ms","hash":645123963,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30449664,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:39:21.533794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":645123963,"revision":19356,"compact-revision":16966} {"level":"warn","ts":"2026-08-31T07:40:01.497937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.483173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:01.498036Z","caller":"traceutil/trace.go:171","msg":"trace[750463759] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:21891; }","duration":"114.650426ms","start":"2026-08-31T07:40:01.383364Z","end":"2026-08-31T07:40:01.498014Z","steps":["trace[750463759] 'count revisions from in-memory index tree' (duration: 114.38432ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:01.644637Z","caller":"traceutil/trace.go:171","msg":"trace[696699803] transaction","detail":"{read_only:false; response_revision:21892; number_of_response:1; }","duration":"141.013201ms","start":"2026-08-31T07:40:01.503605Z","end":"2026-08-31T07:40:01.644618Z","steps":["trace[696699803] 'process raft request' (duration: 140.895828ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:01.651483Z","caller":"traceutil/trace.go:171","msg":"trace[1466200457] linearizableReadLoop","detail":"{readStateIndex:22644; appliedIndex:22643; }","duration":"142.329238ms","start":"2026-08-31T07:40:01.509126Z","end":"2026-08-31T07:40:01.651456Z","steps":["trace[1466200457] 'read index received' (duration: 135.488932ms)","trace[1466200457] 'applied index is now lower than readState.Index' (duration: 6.839436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:40:01.651662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.517332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-31T07:40:01.651695Z","caller":"traceutil/trace.go:171","msg":"trace[608879935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21893; }","duration":"142.598124ms","start":"2026-08-31T07:40:01.509085Z","end":"2026-08-31T07:40:01.651684Z","steps":["trace[608879935] 'agreement among raft nodes before linearized reading' (duration: 142.456001ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:01.651996Z","caller":"traceutil/trace.go:171","msg":"trace[2039306500] transaction","detail":"{read_only:false; response_revision:21893; number_of_response:1; }","duration":"143.191387ms","start":"2026-08-31T07:40:01.508796Z","end":"2026-08-31T07:40:01.651987Z","steps":["trace[2039306500] 'process raft request' (duration: 142.547993ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:08.900074Z","caller":"traceutil/trace.go:171","msg":"trace[1036963035] linearizableReadLoop","detail":"{readStateIndex:22702; appliedIndex:22701; }","duration":"137.846592ms","start":"2026-08-31T07:40:08.762186Z","end":"2026-08-31T07:40:08.900033Z","steps":["trace[1036963035] 'read index received' (duration: 137.561646ms)","trace[1036963035] 'applied index is now lower than readState.Index' (duration: 283.806µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:40:08.900201Z","caller":"traceutil/trace.go:171","msg":"trace[417982473] transaction","detail":"{read_only:false; response_revision:21950; number_of_response:1; }","duration":"162.00946ms","start":"2026-08-31T07:40:08.73818Z","end":"2026-08-31T07:40:08.90019Z","steps":["trace[417982473] 'process raft request' (duration: 161.655231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:08.900755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.543088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-31T07:40:08.900851Z","caller":"traceutil/trace.go:171","msg":"trace[1818383494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21950; }","duration":"138.678831ms","start":"2026-08-31T07:40:08.762152Z","end":"2026-08-31T07:40:08.900831Z","steps":["trace[1818383494] 'agreement among raft nodes before linearized reading' (duration: 138.433636ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:09.01732Z","caller":"traceutil/trace.go:171","msg":"trace[1951019295] transaction","detail":"{read_only:false; response_revision:21951; number_of_response:1; }","duration":"111.381535ms","start":"2026-08-31T07:40:08.905904Z","end":"2026-08-31T07:40:09.017286Z","steps":["trace[1951019295] 'process raft request' (duration: 45.200988ms)","trace[1951019295] 'compare' (duration: 65.700267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:40:09.242155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.240819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:09.242309Z","caller":"traceutil/trace.go:171","msg":"trace[737831764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:21951; }","duration":"115.464534ms","start":"2026-08-31T07:40:09.12682Z","end":"2026-08-31T07:40:09.242284Z","steps":["trace[737831764] 'count revisions from in-memory index tree' (duration: 115.131157ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:10.124763Z","caller":"traceutil/trace.go:171","msg":"trace[442774806] transaction","detail":"{read_only:false; response_revision:21954; number_of_response:1; }","duration":"360.086623ms","start":"2026-08-31T07:40:09.76465Z","end":"2026-08-31T07:40:10.124737Z","steps":["trace[442774806] 'process raft request' (duration: 359.896089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:10.124944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:40:09.76461Z","time spent":"360.223526ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:40:10.625382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.11076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-31T07:40:10.626054Z","caller":"traceutil/trace.go:171","msg":"trace[667721500] transaction","detail":"{read_only:false; response_revision:21955; number_of_response:1; }","duration":"286.081477ms","start":"2026-08-31T07:40:10.339955Z","end":"2026-08-31T07:40:10.626036Z","steps":["trace[667721500] 'process raft request' (duration: 285.499084ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:10.632671Z","caller":"traceutil/trace.go:171","msg":"trace[371654912] transaction","detail":"{read_only:false; response_revision:21957; number_of_response:1; }","duration":"205.419678ms","start":"2026-08-31T07:40:10.427227Z","end":"2026-08-31T07:40:10.632646Z","steps":["trace[371654912] 'process raft request' (duration: 205.291295ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:10.63285Z","caller":"traceutil/trace.go:171","msg":"trace[773633092] transaction","detail":"{read_only:false; response_revision:21956; number_of_response:1; }","duration":"231.536688ms","start":"2026-08-31T07:40:10.401297Z","end":"2026-08-31T07:40:10.632834Z","steps":["trace[773633092] 'process raft request' (duration: 224.732173ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:10.6331Z","caller":"traceutil/trace.go:171","msg":"trace[551668309] transaction","detail":"{read_only:false; response_revision:21958; number_of_response:1; }","duration":"187.74919ms","start":"2026-08-31T07:40:10.445294Z","end":"2026-08-31T07:40:10.633043Z","steps":["trace[551668309] 'process raft request' (duration: 187.279231ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:21.398038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21663} {"level":"info","ts":"2026-08-31T07:44:21.504269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21663,"took":"101.79982ms","hash":762705419,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":41783296,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-31T07:44:21.504329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":762705419,"revision":21663,"compact-revision":19356} {"level":"warn","ts":"2026-08-31T07:44:23.617577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.601859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-31T07:44:23.617691Z","caller":"traceutil/trace.go:171","msg":"trace[466306347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24502; }","duration":"138.755852ms","start":"2026-08-31T07:44:23.478909Z","end":"2026-08-31T07:44:23.617665Z","steps":["trace[466306347] 'range keys from in-memory index tree' (duration: 138.427465ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:46:20.548511Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067d9.wal"} {"level":"info","ts":"2026-08-31T07:49:21.406099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24455} {"level":"info","ts":"2026-08-31T07:49:21.548833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24455,"took":"138.363518ms","hash":1429861773,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":34873344,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-31T07:49:21.549017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1429861773,"revision":24455,"compact-revision":21663} {"level":"info","ts":"2026-08-31T07:53:43.798679Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd65ba9af29ebfc9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:53:43.809517Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-31T07:53:43.809634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-31T07:54:21.412635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27054} {"level":"info","ts":"2026-08-31T07:54:21.537248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27054,"took":"121.251376ms","hash":3536479701,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":29908992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:54:21.537313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3536479701,"revision":27054,"compact-revision":24455}