{"level":"warn","ts":"2026-10-14T02:23:58.651528Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:23:58.651615Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.55: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.55:2380","--initial-cluster=instance=https://162.253.55.55:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.55:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.55: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-10-14T02:23:58.651673Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:23:58.651681Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-10-14T02:23:58.651698Z","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-10-14T02:23:58.652245Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"]} {"level":"info","ts":"2026-10-14T02:23:58.652324Z","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.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.55: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-10-14T02:23:58.656401Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.892058ms"} {"level":"info","ts":"2026-10-14T02:23:58.66124Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"357bf0025115def9","cluster-id":"5f69314e1ae3c800"} {"level":"info","ts":"2026-10-14T02:23:58.66131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T02:23:58.661326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 0"} {"level":"info","ts":"2026-10-14T02:23:58.661333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 357bf0025115def9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T02:23:58.66134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 1"} {"level":"info","ts":"2026-10-14T02:23:58.661374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"warn","ts":"2026-10-14T02:23:58.665798Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T02:23:58.667207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T02:23:58.668377Z","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-10-14T02:23:58.669922Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"357bf0025115def9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T02:23:58.670134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"357bf0025115def9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T02:23:58.670216Z","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-10-14T02:23:58.670289Z","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-10-14T02:23:58.670299Z","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-10-14T02:23:58.671998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"info","ts":"2026-10-14T02:23:58.672214Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","added-peer-id":"357bf0025115def9","added-peer-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-10-14T02:23:58.672918Z","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-10-14T02:23:58.673048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-10-14T02:23:58.673107Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-10-14T02:23:58.673122Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"357bf0025115def9","initial-advertise-peer-urls":["https://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T02:23:58.67314Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T02:23:58.762019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T02:23:58.762081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T02:23:58.762102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgPreVoteResp from 357bf0025115def9 at term 1"} {"level":"info","ts":"2026-10-14T02:23:58.76212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became candidate at term 2"} {"level":"info","ts":"2026-10-14T02:23:58.762131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgVoteResp from 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-10-14T02:23:58.762195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became leader at term 2"} {"level":"info","ts":"2026-10-14T02:23:58.762233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 357bf0025115def9 elected leader 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-10-14T02:23:58.763059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:23:58.764005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:23:58.763998Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"357bf0025115def9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.55:2379]}","request-path":"/0/members/357bf0025115def9/attributes","cluster-id":"5f69314e1ae3c800","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T02:23:58.764094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:23:58.764299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T02:23:58.764422Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T02:23:58.764677Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:23:58.764818Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:23:58.76485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:23:58.767429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T02:23:58.767439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.55:2379"} {"level":"info","ts":"2026-10-14T02:24:02.542047Z","caller":"traceutil/trace.go:171","msg":"trace[1477548222] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"128.547001ms","start":"2026-10-14T02:24:02.413472Z","end":"2026-10-14T02:24:02.542019Z","steps":["trace[1477548222] 'process raft request' (duration: 46.962958ms)","trace[1477548222] 'compare' (duration: 81.46184ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:25:00.440563Z","caller":"traceutil/trace.go:171","msg":"trace[730666804] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"133.389552ms","start":"2026-10-14T02:25:00.307133Z","end":"2026-10-14T02:25:00.440522Z","steps":["trace[730666804] 'process raft request' (duration: 133.231298ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.454778Z","caller":"traceutil/trace.go:171","msg":"trace[1566227115] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"142.179839ms","start":"2026-10-14T02:25:00.312583Z","end":"2026-10-14T02:25:00.454763Z","steps":["trace[1566227115] 'process raft request' (duration: 141.966253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.454778Z","caller":"traceutil/trace.go:171","msg":"trace[31225332] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"141.498062ms","start":"2026-10-14T02:25:00.313257Z","end":"2026-10-14T02:25:00.454755Z","steps":["trace[31225332] 'process raft request' (duration: 141.377668ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.454898Z","caller":"traceutil/trace.go:171","msg":"trace[823064203] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"127.029029ms","start":"2026-10-14T02:25:00.327823Z","end":"2026-10-14T02:25:00.454852Z","steps":["trace[823064203] 'process raft request' (duration: 126.847294ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455017Z","caller":"traceutil/trace.go:171","msg":"trace[1786878286] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"126.150947ms","start":"2026-10-14T02:25:00.328827Z","end":"2026-10-14T02:25:00.454978Z","steps":["trace[1786878286] 'process raft request' (duration: 125.86986ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455155Z","caller":"traceutil/trace.go:171","msg":"trace[1230350487] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"124.716549ms","start":"2026-10-14T02:25:00.330427Z","end":"2026-10-14T02:25:00.455144Z","steps":["trace[1230350487] 'process raft request' (duration: 124.492604ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455201Z","caller":"traceutil/trace.go:171","msg":"trace[1943398268] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"123.95482ms","start":"2026-10-14T02:25:00.33121Z","end":"2026-10-14T02:25:00.455164Z","steps":["trace[1943398268] 'process raft request' (duration: 123.863068ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455241Z","caller":"traceutil/trace.go:171","msg":"trace[466662328] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"123.107059ms","start":"2026-10-14T02:25:00.332125Z","end":"2026-10-14T02:25:00.455232Z","steps":["trace[466662328] 'process raft request' (duration: 122.980995ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455285Z","caller":"traceutil/trace.go:171","msg":"trace[727123543] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"104.181232ms","start":"2026-10-14T02:25:00.351086Z","end":"2026-10-14T02:25:00.455268Z","steps":["trace[727123543] 'process raft request' (duration: 104.050509ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:00.455443Z","caller":"traceutil/trace.go:171","msg":"trace[1151436369] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"104.0726ms","start":"2026-10-14T02:25:00.351332Z","end":"2026-10-14T02:25:00.455405Z","steps":["trace[1151436369] 'process raft request' (duration: 103.854214ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:07.112042Z","caller":"traceutil/trace.go:171","msg":"trace[1839661653] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"157.607744ms","start":"2026-10-14T02:25:06.954406Z","end":"2026-10-14T02:25:07.112013Z","steps":["trace[1839661653] 'read index received' (duration: 156.742172ms)","trace[1839661653] 'applied index is now lower than readState.Index' (duration: 864.662µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:25:07.112171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.750747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:25:07.112261Z","caller":"traceutil/trace.go:171","msg":"trace[348999259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"157.899391ms","start":"2026-10-14T02:25:06.954348Z","end":"2026-10-14T02:25:07.112248Z","steps":["trace[348999259] 'agreement among raft nodes before linearized reading' (duration: 157.768627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:07.112421Z","caller":"traceutil/trace.go:171","msg":"trace[105007165] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"201.950002ms","start":"2026-10-14T02:25:06.910146Z","end":"2026-10-14T02:25:07.112096Z","steps":["trace[105007165] 'process raft request' (duration: 201.191673ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:07.531883Z","caller":"traceutil/trace.go:171","msg":"trace[956701004] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"167.672643ms","start":"2026-10-14T02:25:07.364187Z","end":"2026-10-14T02:25:07.53186Z","steps":["trace[956701004] 'process raft request' (duration: 167.510508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:25:07.760506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.014665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:25:07.760693Z","caller":"traceutil/trace.go:171","msg":"trace[150873170] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"198.173695ms","start":"2026-10-14T02:25:07.562417Z","end":"2026-10-14T02:25:07.76059Z","steps":["trace[150873170] 'process raft request' (duration: 16.567445ms)","trace[150873170] 'compare' (duration: 180.886121ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:25:07.808144Z","caller":"traceutil/trace.go:171","msg":"trace[1254873970] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"202.014003ms","start":"2026-10-14T02:25:07.606096Z","end":"2026-10-14T02:25:07.80811Z","steps":["trace[1254873970] 'process raft request' (duration: 200.475703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:25:08.014305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.174041ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-14T02:25:08.014649Z","caller":"traceutil/trace.go:171","msg":"trace[1420464266] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"121.803956ms","start":"2026-10-14T02:25:07.892803Z","end":"2026-10-14T02:25:08.014607Z","steps":["trace[1420464266] 'process raft request' (duration: 121.60621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:25:23.163021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.74272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:25:23.163322Z","caller":"traceutil/trace.go:171","msg":"trace[274851399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"208.13366ms","start":"2026-10-14T02:25:22.955168Z","end":"2026-10-14T02:25:23.163302Z","steps":["trace[274851399] 'range keys from in-memory index tree' (duration: 207.591687ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:51.625315Z","caller":"traceutil/trace.go:171","msg":"trace[1483666044] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"186.166899ms","start":"2026-10-14T02:25:51.438933Z","end":"2026-10-14T02:25:51.6251Z","steps":["trace[1483666044] 'process raft request' (duration: 186.044766ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:25:51.627078Z","caller":"traceutil/trace.go:171","msg":"trace[739071580] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"135.347148ms","start":"2026-10-14T02:25:51.491711Z","end":"2026-10-14T02:25:51.627058Z","steps":["trace[739071580] 'process raft request' (duration: 135.275157ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:06.543061Z","caller":"traceutil/trace.go:171","msg":"trace[1917113252] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"103.978644ms","start":"2026-10-14T02:26:06.439058Z","end":"2026-10-14T02:26:06.543037Z","steps":["trace[1917113252] 'process raft request' (duration: 99.939441ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:13.667417Z","caller":"traceutil/trace.go:171","msg":"trace[1256827211] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"109.761152ms","start":"2026-10-14T02:26:13.557606Z","end":"2026-10-14T02:26:13.667368Z","steps":["trace[1256827211] 'process raft request' (duration: 109.589328ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:39.995735Z","caller":"traceutil/trace.go:171","msg":"trace[1578441376] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"149.799188ms","start":"2026-10-14T02:26:39.845893Z","end":"2026-10-14T02:26:39.995692Z","steps":["trace[1578441376] 'read index received' (duration: 149.659165ms)","trace[1578441376] 'applied index is now lower than readState.Index' (duration: 138.923µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:39.9958Z","caller":"traceutil/trace.go:171","msg":"trace[954694769] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"207.365243ms","start":"2026-10-14T02:26:39.788399Z","end":"2026-10-14T02:26:39.995764Z","steps":["trace[954694769] 'process raft request' (duration: 207.163648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:39.99606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.864217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-14T02:26:39.996117Z","caller":"traceutil/trace.go:171","msg":"trace[2053016870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1939; }","duration":"144.017281ms","start":"2026-10-14T02:26:39.852085Z","end":"2026-10-14T02:26:39.996102Z","steps":["trace[2053016870] 'agreement among raft nodes before linearized reading' (duration: 143.790965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:39.996091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.183389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T02:26:39.996206Z","caller":"traceutil/trace.go:171","msg":"trace[590748586] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1939; }","duration":"150.332442ms","start":"2026-10-14T02:26:39.845835Z","end":"2026-10-14T02:26:39.996167Z","steps":["trace[590748586] 'agreement among raft nodes before linearized reading' (duration: 149.963173ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:53.351083Z","caller":"traceutil/trace.go:171","msg":"trace[1295833225] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"254.131514ms","start":"2026-10-14T02:26:53.096927Z","end":"2026-10-14T02:26:53.351059Z","steps":["trace[1295833225] 'read index received' (duration: 253.897408ms)","trace[1295833225] 'applied index is now lower than readState.Index' (duration: 233.196µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:53.351156Z","caller":"traceutil/trace.go:171","msg":"trace[2070517932] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"373.760609ms","start":"2026-10-14T02:26:52.977359Z","end":"2026-10-14T02:26:53.35112Z","steps":["trace[2070517932] 'process raft request' (duration: 373.526933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:53.351431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:52.977336Z","time spent":"373.984674ms","remote":"127.0.0.1:37354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5917,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:26:53.351539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.082429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T02:26:53.351599Z","caller":"traceutil/trace.go:171","msg":"trace[804362308] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2052; }","duration":"195.158981ms","start":"2026-10-14T02:26:53.156424Z","end":"2026-10-14T02:26:53.351583Z","steps":["trace[804362308] 'agreement among raft nodes before linearized reading' (duration: 194.915195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:53.351667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.451372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T02:26:53.352131Z","caller":"traceutil/trace.go:171","msg":"trace[1269213104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2052; }","duration":"255.224362ms","start":"2026-10-14T02:26:53.096878Z","end":"2026-10-14T02:26:53.352102Z","steps":["trace[1269213104] 'agreement among raft nodes before linearized reading' (duration: 254.299748ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:56.407287Z","caller":"traceutil/trace.go:171","msg":"trace[143800388] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"127.472667ms","start":"2026-10-14T02:26:56.279787Z","end":"2026-10-14T02:26:56.407259Z","steps":["trace[143800388] 'process raft request' (duration: 127.336253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:27:35.820806Z","caller":"traceutil/trace.go:171","msg":"trace[2047267436] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"160.838342ms","start":"2026-10-14T02:27:35.659943Z","end":"2026-10-14T02:27:35.820782Z","steps":["trace[2047267436] 'read index received' (duration: 160.629517ms)","trace[2047267436] 'applied index is now lower than readState.Index' (duration: 207.905µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:27:35.820894Z","caller":"traceutil/trace.go:171","msg":"trace[1796083055] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"230.756704ms","start":"2026-10-14T02:27:35.590095Z","end":"2026-10-14T02:27:35.820852Z","steps":["trace[1796083055] 'process raft request' (duration: 230.529048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:27:35.820964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.995496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:27:35.821042Z","caller":"traceutil/trace.go:171","msg":"trace[1273014773] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2290; }","duration":"161.13416ms","start":"2026-10-14T02:27:35.65989Z","end":"2026-10-14T02:27:35.821024Z","steps":["trace[1273014773] 'agreement among raft nodes before linearized reading' (duration: 160.994186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:27:35.821107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.897318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-14T02:27:35.821144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.569251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T02:27:35.821155Z","caller":"traceutil/trace.go:171","msg":"trace[745422374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2290; }","duration":"150.98831ms","start":"2026-10-14T02:27:35.670153Z","end":"2026-10-14T02:27:35.821142Z","steps":["trace[745422374] 'agreement among raft nodes before linearized reading' (duration: 150.756343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:27:35.821175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.846008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T02:27:35.821216Z","caller":"traceutil/trace.go:171","msg":"trace[524400574] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2290; }","duration":"113.699005ms","start":"2026-10-14T02:27:35.707499Z","end":"2026-10-14T02:27:35.821198Z","steps":["trace[524400574] 'agreement among raft nodes before linearized reading' (duration: 113.440317ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:27:35.821231Z","caller":"traceutil/trace.go:171","msg":"trace[2139428583] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2290; }","duration":"127.923179ms","start":"2026-10-14T02:27:35.693291Z","end":"2026-10-14T02:27:35.821215Z","steps":["trace[2139428583] 'agreement among raft nodes before linearized reading' (duration: 127.725304ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:28:10.408722Z","caller":"traceutil/trace.go:171","msg":"trace[980647067] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"113.576594ms","start":"2026-10-14T02:28:10.295077Z","end":"2026-10-14T02:28:10.408654Z","steps":["trace[980647067] 'process raft request' (duration: 113.346448ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:23.960112Z","caller":"traceutil/trace.go:171","msg":"trace[1658082095] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"282.570456ms","start":"2026-10-14T02:33:23.677293Z","end":"2026-10-14T02:33:23.959864Z","steps":["trace[1658082095] 'process raft request' (duration: 282.35099ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:23.976564Z","caller":"traceutil/trace.go:171","msg":"trace[393784196] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"285.448939ms","start":"2026-10-14T02:33:23.691081Z","end":"2026-10-14T02:33:23.97653Z","steps":["trace[393784196] 'process raft request' (duration: 285.071879ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:23.976933Z","caller":"traceutil/trace.go:171","msg":"trace[1484980576] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"140.399031ms","start":"2026-10-14T02:33:23.836496Z","end":"2026-10-14T02:33:23.976895Z","steps":["trace[1484980576] 'process raft request' (duration: 139.846346ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:23.977164Z","caller":"traceutil/trace.go:171","msg":"trace[694572589] linearizableReadLoop","detail":"{readStateIndex:5454; appliedIndex:5452; }","duration":"248.174895ms","start":"2026-10-14T02:33:23.728669Z","end":"2026-10-14T02:33:23.976844Z","steps":["trace[694572589] 'read index received' (duration: 231.030524ms)","trace[694572589] 'applied index is now lower than readState.Index' (duration: 17.143001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:33:23.977381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.684948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwjj4\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-10-14T02:33:23.977493Z","caller":"traceutil/trace.go:171","msg":"trace[2015932015] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwjj4; range_end:; response_count:1; response_revision:5256; }","duration":"248.81271ms","start":"2026-10-14T02:33:23.728636Z","end":"2026-10-14T02:33:23.977449Z","steps":["trace[2015932015] 'agreement among raft nodes before linearized reading' (duration: 248.549514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:23.977997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.196235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18de44aae2bf3fd3\" ","response":"range_response_count:1 size:943"} {"level":"warn","ts":"2026-10-14T02:33:23.978008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.638717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62cb6a8f-9498-4903-87e3-727a3c765e99\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-14T02:33:23.978073Z","caller":"traceutil/trace.go:171","msg":"trace[1776482913] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18de44aae2bf3fd3; range_end:; response_count:1; response_revision:5256; }","duration":"202.375599ms","start":"2026-10-14T02:33:23.775673Z","end":"2026-10-14T02:33:23.978049Z","steps":["trace[1776482913] 'agreement among raft nodes before linearized reading' (duration: 201.834516ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:23.978082Z","caller":"traceutil/trace.go:171","msg":"trace[1738068808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62cb6a8f-9498-4903-87e3-727a3c765e99; range_end:; response_count:1; response_revision:5256; }","duration":"243.774341ms","start":"2026-10-14T02:33:23.734286Z","end":"2026-10-14T02:33:23.97806Z","steps":["trace[1738068808] 'agreement among raft nodes before linearized reading' (duration: 243.071533ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:24.384634Z","caller":"traceutil/trace.go:171","msg":"trace[1259969769] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"170.678227ms","start":"2026-10-14T02:33:24.213932Z","end":"2026-10-14T02:33:24.38461Z","steps":["trace[1259969769] 'process raft request' (duration: 170.554083ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:24.385582Z","caller":"traceutil/trace.go:171","msg":"trace[375153296] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"171.010915ms","start":"2026-10-14T02:33:24.214549Z","end":"2026-10-14T02:33:24.38556Z","steps":["trace[375153296] 'process raft request' (duration: 170.880671ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:39.819174Z","caller":"traceutil/trace.go:171","msg":"trace[1095746281] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"104.040488ms","start":"2026-10-14T02:33:39.715098Z","end":"2026-10-14T02:33:39.819139Z","steps":["trace[1095746281] 'process raft request' (duration: 103.818712ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:39.833461Z","caller":"traceutil/trace.go:171","msg":"trace[89367530] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"118.052807ms","start":"2026-10-14T02:33:39.715376Z","end":"2026-10-14T02:33:39.833429Z","steps":["trace[89367530] 'process raft request' (duration: 117.734489ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.534706Z","caller":"traceutil/trace.go:171","msg":"trace[1520885939] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"518.093306ms","start":"2026-10-14T02:33:40.016581Z","end":"2026-10-14T02:33:40.534674Z","steps":["trace[1520885939] 'process raft request' (duration: 518.005994ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.534708Z","caller":"traceutil/trace.go:171","msg":"trace[1532236341] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"525.70168ms","start":"2026-10-14T02:33:40.008977Z","end":"2026-10-14T02:33:40.534678Z","steps":["trace[1532236341] 'process raft request' (duration: 525.342931ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.53474Z","caller":"traceutil/trace.go:171","msg":"trace[1553547701] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"524.677854ms","start":"2026-10-14T02:33:40.010004Z","end":"2026-10-14T02:33:40.534682Z","steps":["trace[1553547701] 'process raft request' (duration: 524.52243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.535138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.008933Z","time spent":"526.119741ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T02:33:40.535215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.009992Z","time spent":"525.156756ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T02:33:40.535495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.016556Z","time spent":"518.357212ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:33:40.644989Z","caller":"traceutil/trace.go:171","msg":"trace[674751694] linearizableReadLoop","detail":"{readStateIndex:5782; appliedIndex:5776; }","duration":"580.328792ms","start":"2026-10-14T02:33:40.064629Z","end":"2026-10-14T02:33:40.644957Z","steps":["trace[674751694] 'read index received' (duration: 469.791798ms)","trace[674751694] 'applied index is now lower than readState.Index' (duration: 110.535324ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:33:40.645124Z","caller":"traceutil/trace.go:171","msg":"trace[2129650645] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"598.022756ms","start":"2026-10-14T02:33:40.047061Z","end":"2026-10-14T02:33:40.645083Z","steps":["trace[2129650645] 'process raft request' (duration: 597.165253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.645185Z","caller":"traceutil/trace.go:171","msg":"trace[1638110246] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"588.777989ms","start":"2026-10-14T02:33:40.056356Z","end":"2026-10-14T02:33:40.645134Z","steps":["trace[1638110246] 'process raft request' (duration: 588.541783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.64531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.056343Z","time spent":"588.899052ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:33:40.64531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.047046Z","time spent":"598.170949ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:33:40.645393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.721572ms","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-10-14T02:33:40.645452Z","caller":"traceutil/trace.go:171","msg":"trace[1308124888] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5572; }","duration":"580.845966ms","start":"2026-10-14T02:33:40.064588Z","end":"2026-10-14T02:33:40.645434Z","steps":["trace[1308124888] 'agreement among raft nodes before linearized reading' (duration: 580.563678ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.645489Z","caller":"traceutil/trace.go:171","msg":"trace[1701874811] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"598.318103ms","start":"2026-10-14T02:33:40.047144Z","end":"2026-10-14T02:33:40.645462Z","steps":["trace[1701874811] 'process raft request' (duration: 597.690717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.64554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.064573Z","time spent":"580.920727ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":730,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-10-14T02:33:40.645617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.047138Z","time spent":"598.425146ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:33:40.646137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.423554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-14T02:33:40.64618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.380558ms","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-10-14T02:33:40.64622Z","caller":"traceutil/trace.go:171","msg":"trace[1372635878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5572; }","duration":"580.515986ms","start":"2026-10-14T02:33:40.065686Z","end":"2026-10-14T02:33:40.646202Z","steps":["trace[1372635878] 'agreement among raft nodes before linearized reading' (duration: 580.27611ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:40.646254Z","caller":"traceutil/trace.go:171","msg":"trace[2066069034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5572; }","duration":"168.48467ms","start":"2026-10-14T02:33:40.477752Z","end":"2026-10-14T02:33:40.646236Z","steps":["trace[2066069034] 'agreement among raft nodes before linearized reading' (duration: 168.297445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.646269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.065683Z","time spent":"580.574947ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T02:33:40.646361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.611032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:33:40.646419Z","caller":"traceutil/trace.go:171","msg":"trace[938354782] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:0; response_revision:5572; }","duration":"102.702064ms","start":"2026-10-14T02:33:40.543704Z","end":"2026-10-14T02:33:40.646406Z","steps":["trace[938354782] 'agreement among raft nodes before linearized reading' (duration: 102.616312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.646448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.467471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwjj4\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-10-14T02:33:40.646474Z","caller":"traceutil/trace.go:171","msg":"trace[1082658803] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwjj4; range_end:; response_count:1; response_revision:5572; }","duration":"551.525543ms","start":"2026-10-14T02:33:40.094939Z","end":"2026-10-14T02:33:40.646465Z","steps":["trace[1082658803] 'agreement among raft nodes before linearized reading' (duration: 551.4298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.646536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.094916Z","time spent":"551.582134ms","remote":"127.0.0.1:37354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6469,"request content":"key:\"/registry/pods/openstack/keepalived-zwjj4\" "} {"level":"warn","ts":"2026-10-14T02:33:40.646668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.36724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T02:33:40.647858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.289655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-14T02:33:40.647956Z","caller":"traceutil/trace.go:171","msg":"trace[950617610] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5572; }","duration":"416.455439ms","start":"2026-10-14T02:33:40.231484Z","end":"2026-10-14T02:33:40.64794Z","steps":["trace[950617610] 'agreement among raft nodes before linearized reading' (duration: 414.856207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:40.648046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:33:40.23146Z","time spent":"416.572152ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225423,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-10-14T02:33:40.648778Z","caller":"traceutil/trace.go:171","msg":"trace[863710823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5572; }","duration":"174.484404ms","start":"2026-10-14T02:33:40.472256Z","end":"2026-10-14T02:33:40.64674Z","steps":["trace[863710823] 'agreement among raft nodes before linearized reading' (duration: 174.3343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:33:49.410853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.544694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbef42a4-3ef6-4fd1-bb87-a3839a435404\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-14T02:33:49.41095Z","caller":"traceutil/trace.go:171","msg":"trace[828468154] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbef42a4-3ef6-4fd1-bb87-a3839a435404; range_end:; response_count:1; response_revision:5739; }","duration":"101.710529ms","start":"2026-10-14T02:33:49.309218Z","end":"2026-10-14T02:33:49.410929Z","steps":["trace[828468154] 'range keys from in-memory index tree' (duration: 101.32746ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:49.980557Z","caller":"traceutil/trace.go:171","msg":"trace[1702046244] transaction","detail":"{read_only:false; response_revision:5740; number_of_response:1; }","duration":"100.615491ms","start":"2026-10-14T02:33:49.879891Z","end":"2026-10-14T02:33:49.980506Z","steps":["trace[1702046244] 'process raft request' (duration: 100.393385ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:33:58.991906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2944} {"level":"info","ts":"2026-10-14T02:33:59.087502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2944,"took":"93.94094ms","hash":3806541564,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":32296960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T02:33:59.087598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3806541564,"revision":2944,"compact-revision":-1} {"level":"info","ts":"2026-10-14T02:38:58.998953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5843} {"level":"info","ts":"2026-10-14T02:38:59.095682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5843,"took":"94.27541ms","hash":4117201900,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":25956352,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T02:38:59.09576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4117201900,"revision":5843,"compact-revision":2944} {"level":"warn","ts":"2026-10-14T02:40:20.774647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.083745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:40:20.775669Z","caller":"traceutil/trace.go:171","msg":"trace[1600302696] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"192.126731ms","start":"2026-10-14T02:40:20.583516Z","end":"2026-10-14T02:40:20.775643Z","steps":["trace[1600302696] 'process raft request' (duration: 192.07856ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:40:20.775636Z","caller":"traceutil/trace.go:171","msg":"trace[930169022] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"381.379987ms","start":"2026-10-14T02:40:20.394189Z","end":"2026-10-14T02:40:20.775569Z","steps":["trace[930169022] 'process raft request' (duration: 152.248337ms)","trace[930169022] 'compare' (duration: 227.452707ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:40:20.775912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:40:20.394155Z","time spent":"381.621374ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:40:20.776052Z","caller":"traceutil/trace.go:171","msg":"trace[874478504] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"377.927899ms","start":"2026-10-14T02:40:20.398113Z","end":"2026-10-14T02:40:20.776041Z","steps":["trace[874478504] 'process raft request' (duration: 377.355295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:40:20.776128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:40:20.398078Z","time spent":"378.007332ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:40:21.311213Z","caller":"traceutil/trace.go:171","msg":"trace[1919028038] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"123.299914ms","start":"2026-10-14T02:40:21.187882Z","end":"2026-10-14T02:40:21.311182Z","steps":["trace[1919028038] 'process raft request' (duration: 123.107189ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:40:21.470258Z","caller":"traceutil/trace.go:171","msg":"trace[939952856] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"280.992321ms","start":"2026-10-14T02:40:21.189216Z","end":"2026-10-14T02:40:21.470209Z","steps":["trace[939952856] 'process raft request' (duration: 233.233305ms)","trace[939952856] 'compare' (duration: 47.482049ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:40:22.284266Z","caller":"traceutil/trace.go:171","msg":"trace[264460328] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"104.782718ms","start":"2026-10-14T02:40:22.17945Z","end":"2026-10-14T02:40:22.284232Z","steps":["trace[264460328] 'process raft request' (duration: 104.44584ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:40:22.765665Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002319.wal"} {"level":"info","ts":"2026-10-14T02:40:51.821153Z","caller":"traceutil/trace.go:171","msg":"trace[1160238020] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"102.661957ms","start":"2026-10-14T02:40:51.718463Z","end":"2026-10-14T02:40:51.821125Z","steps":["trace[1160238020] 'process raft request' (duration: 102.605995ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:40:51.821212Z","caller":"traceutil/trace.go:171","msg":"trace[1860740375] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"160.982493ms","start":"2026-10-14T02:40:51.660185Z","end":"2026-10-14T02:40:51.821168Z","steps":["trace[1860740375] 'process raft request' (duration: 136.72912ms)","trace[1860740375] 'compare' (duration: 23.981306ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:40:51.821652Z","caller":"traceutil/trace.go:171","msg":"trace[721978354] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9201; }","duration":"133.725313ms","start":"2026-10-14T02:40:51.687913Z","end":"2026-10-14T02:40:51.821639Z","steps":["trace[721978354] 'read index received' (duration: 108.996928ms)","trace[721978354] 'applied index is now lower than readState.Index' (duration: 24.726775ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:40:51.82183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.894677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwjj4\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-10-14T02:40:51.821873Z","caller":"traceutil/trace.go:171","msg":"trace[1634626398] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwjj4; range_end:; response_count:1; response_revision:8884; }","duration":"133.975639ms","start":"2026-10-14T02:40:51.687883Z","end":"2026-10-14T02:40:51.821858Z","steps":["trace[1634626398] 'agreement among raft nodes before linearized reading' (duration: 133.809425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:41:02.733139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.089364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:41:02.733402Z","caller":"traceutil/trace.go:171","msg":"trace[74245142] transaction","detail":"{read_only:false; response_revision:8939; number_of_response:1; }","duration":"181.739726ms","start":"2026-10-14T02:41:02.551566Z","end":"2026-10-14T02:41:02.733306Z","steps":["trace[74245142] 'process raft request' (duration: 24.371415ms)","trace[74245142] 'compare' (duration: 156.92926ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:41:02.73342Z","caller":"traceutil/trace.go:171","msg":"trace[181486030] linearizableReadLoop","detail":"{readStateIndex:9261; appliedIndex:9260; }","duration":"160.928072ms","start":"2026-10-14T02:41:02.572424Z","end":"2026-10-14T02:41:02.733352Z","steps":["trace[181486030] 'read index received' (duration: 3.443288ms)","trace[181486030] 'applied index is now lower than readState.Index' (duration: 157.481104ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:41:02.733785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.325723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T02:41:02.733868Z","caller":"traceutil/trace.go:171","msg":"trace[1465806931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8940; }","duration":"161.513438ms","start":"2026-10-14T02:41:02.572332Z","end":"2026-10-14T02:41:02.733846Z","steps":["trace[1465806931] 'agreement among raft nodes before linearized reading' (duration: 161.184918ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:41:05.411886Z","caller":"traceutil/trace.go:171","msg":"trace[1930171181] linearizableReadLoop","detail":"{readStateIndex:9286; appliedIndex:9285; }","duration":"143.393733ms","start":"2026-10-14T02:41:05.268456Z","end":"2026-10-14T02:41:05.411849Z","steps":["trace[1930171181] 'read index received' (duration: 143.2557ms)","trace[1930171181] 'applied index is now lower than readState.Index' (duration: 136.583µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:41:05.411932Z","caller":"traceutil/trace.go:171","msg":"trace[787243515] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"143.837834ms","start":"2026-10-14T02:41:05.268059Z","end":"2026-10-14T02:41:05.411897Z","steps":["trace[787243515] 'process raft request' (duration: 143.578477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:41:05.412246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.755422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vmbpt\" ","response":"range_response_count:1 size:2801"} {"level":"info","ts":"2026-10-14T02:41:05.412342Z","caller":"traceutil/trace.go:171","msg":"trace[1030458314] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vmbpt; range_end:; response_count:1; response_revision:8964; }","duration":"143.914426ms","start":"2026-10-14T02:41:05.268405Z","end":"2026-10-14T02:41:05.412319Z","steps":["trace[1030458314] 'agreement among raft nodes before linearized reading' (duration: 143.612268ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:41:05.470685Z","caller":"traceutil/trace.go:171","msg":"trace[466833931] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"197.736258ms","start":"2026-10-14T02:41:05.272906Z","end":"2026-10-14T02:41:05.470642Z","steps":["trace[466833931] 'process raft request' (duration: 191.830225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:41:05.470925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.746822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T02:41:05.471025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.695572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vmbpt\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-10-14T02:41:05.471032Z","caller":"traceutil/trace.go:171","msg":"trace[151204853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8965; }","duration":"194.909716ms","start":"2026-10-14T02:41:05.276095Z","end":"2026-10-14T02:41:05.471004Z","steps":["trace[151204853] 'agreement among raft nodes before linearized reading' (duration: 194.604668ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:41:05.471114Z","caller":"traceutil/trace.go:171","msg":"trace[325297128] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vmbpt; range_end:; response_count:1; response_revision:8965; }","duration":"198.837596ms","start":"2026-10-14T02:41:05.272253Z","end":"2026-10-14T02:41:05.471091Z","steps":["trace[325297128] 'agreement among raft nodes before linearized reading' (duration: 198.553689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:41:05.471259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.03166ms","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-10-14T02:41:05.471642Z","caller":"traceutil/trace.go:171","msg":"trace[231573554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8965; }","duration":"109.463511ms","start":"2026-10-14T02:41:05.362139Z","end":"2026-10-14T02:41:05.471602Z","steps":["trace[231573554] 'agreement among raft nodes before linearized reading' (duration: 108.520717ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:42:11.921045Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T02:42:11.929764Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T02:42:11.9299Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T02:43:59.006048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8001} {"level":"info","ts":"2026-10-14T02:43:59.081516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8001,"took":"72.951461ms","hash":1126090978,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T02:43:59.081603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1126090978,"revision":8001,"compact-revision":5843} {"level":"info","ts":"2026-10-14T02:47:57.700308Z","caller":"traceutil/trace.go:171","msg":"trace[1133962458] transaction","detail":"{read_only:false; response_revision:12156; number_of_response:1; }","duration":"111.473132ms","start":"2026-10-14T02:47:57.588805Z","end":"2026-10-14T02:47:57.700278Z","steps":["trace[1133962458] 'process raft request' (duration: 95.781179ms)","trace[1133962458] 'compare' (duration: 15.443297ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:47:57.704633Z","caller":"traceutil/trace.go:171","msg":"trace[658398070] transaction","detail":"{read_only:false; response_revision:12157; number_of_response:1; }","duration":"114.196202ms","start":"2026-10-14T02:47:57.590399Z","end":"2026-10-14T02:47:57.704595Z","steps":["trace[658398070] 'process raft request' (duration: 114.022507ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:48:18.927127Z","caller":"traceutil/trace.go:171","msg":"trace[398544352] transaction","detail":"{read_only:false; response_revision:12276; number_of_response:1; }","duration":"106.896665ms","start":"2026-10-14T02:48:18.820196Z","end":"2026-10-14T02:48:18.927093Z","steps":["trace[398544352] 'process raft request' (duration: 106.449963ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:48:59.013548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10371} {"level":"info","ts":"2026-10-14T02:48:59.109614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10371,"took":"92.70119ms","hash":442559269,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":26451968,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T02:48:59.109708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":442559269,"revision":10371,"compact-revision":8001} {"level":"info","ts":"2026-10-14T02:53:59.019293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12558} {"level":"info","ts":"2026-10-14T02:53:59.094996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12558,"took":"73.451465ms","hash":1719274800,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":27947008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T02:53:59.095118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1719274800,"revision":12558,"compact-revision":10371} {"level":"warn","ts":"2026-10-14T02:54:22.849871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:54:22.850018Z","caller":"traceutil/trace.go:171","msg":"trace[1316121038] transaction","detail":"{read_only:false; response_revision:14746; number_of_response:1; }","duration":"170.146707ms","start":"2026-10-14T02:54:22.67985Z","end":"2026-10-14T02:54:22.849997Z","steps":["trace[1316121038] 'process raft request' (duration: 11.6786ms)","trace[1316121038] 'compare' (duration: 158.058756ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:55:03.726412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.706533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T02:55:03.726517Z","caller":"traceutil/trace.go:171","msg":"trace[1552856252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14969; }","duration":"128.882769ms","start":"2026-10-14T02:55:03.597613Z","end":"2026-10-14T02:55:03.726495Z","steps":["trace[1552856252] 'range keys from in-memory index tree' (duration: 128.494388ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:55:03.874076Z","caller":"traceutil/trace.go:171","msg":"trace[1230616772] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"142.488657ms","start":"2026-10-14T02:55:03.731557Z","end":"2026-10-14T02:55:03.874046Z","steps":["trace[1230616772] 'process raft request' (duration: 142.305973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:55:29.695138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.260426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-82kss\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-10-14T02:55:29.695131Z","caller":"traceutil/trace.go:171","msg":"trace[791593924] transaction","detail":"{read_only:false; response_revision:15299; number_of_response:1; }","duration":"104.061591ms","start":"2026-10-14T02:55:29.591027Z","end":"2026-10-14T02:55:29.695089Z","steps":["trace[791593924] 'process raft request' (duration: 72.798919ms)","trace[791593924] 'compare' (duration: 31.076817ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:55:29.6953Z","caller":"traceutil/trace.go:171","msg":"trace[1939234170] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-82kss; range_end:; response_count:1; response_revision:15298; }","duration":"277.465452ms","start":"2026-10-14T02:55:29.417782Z","end":"2026-10-14T02:55:29.695247Z","steps":["trace[1939234170] 'range keys from in-memory index tree' (duration: 277.01014ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:57.506768Z","caller":"traceutil/trace.go:171","msg":"trace[813603938] linearizableReadLoop","detail":"{readStateIndex:16674; appliedIndex:16673; }","duration":"159.18523ms","start":"2026-10-14T02:56:57.347547Z","end":"2026-10-14T02:56:57.506732Z","steps":["trace[813603938] 'read index received' (duration: 158.980755ms)","trace[813603938] 'applied index is now lower than readState.Index' (duration: 202.875µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:56:57.506832Z","caller":"traceutil/trace.go:171","msg":"trace[416051500] transaction","detail":"{read_only:false; response_revision:16115; number_of_response:1; }","duration":"159.812066ms","start":"2026-10-14T02:56:57.346977Z","end":"2026-10-14T02:56:57.506789Z","steps":["trace[416051500] 'process raft request' (duration: 159.538969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:56:57.507733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.145784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:732088"} {"level":"info","ts":"2026-10-14T02:56:57.507786Z","caller":"traceutil/trace.go:171","msg":"trace[1937776261] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16115; }","duration":"160.239186ms","start":"2026-10-14T02:56:57.347532Z","end":"2026-10-14T02:56:57.507771Z","steps":["trace[1937776261] 'agreement among raft nodes before linearized reading' (duration: 159.335414ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:57.527472Z","caller":"traceutil/trace.go:171","msg":"trace[441795072] transaction","detail":"{read_only:false; response_revision:16116; number_of_response:1; }","duration":"108.066789ms","start":"2026-10-14T02:56:57.41937Z","end":"2026-10-14T02:56:57.527437Z","steps":["trace[441795072] 'process raft request' (duration: 107.631758ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:57:02.795164Z","caller":"traceutil/trace.go:171","msg":"trace[689054002] transaction","detail":"{read_only:false; response_revision:16141; number_of_response:1; }","duration":"164.213419ms","start":"2026-10-14T02:57:02.630895Z","end":"2026-10-14T02:57:02.795108Z","steps":["trace[689054002] 'process raft request' (duration: 163.740586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:57:02.795465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.619288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T02:57:02.795571Z","caller":"traceutil/trace.go:171","msg":"trace[286172887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16141; }","duration":"123.806512ms","start":"2026-10-14T02:57:02.671744Z","end":"2026-10-14T02:57:02.79555Z","steps":["trace[286172887] 'agreement among raft nodes before linearized reading' (duration: 123.495154ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:57:02.796475Z","caller":"traceutil/trace.go:171","msg":"trace[164252947] linearizableReadLoop","detail":"{readStateIndex:16702; appliedIndex:16701; }","duration":"123.181096ms","start":"2026-10-14T02:57:02.671824Z","end":"2026-10-14T02:57:02.795005Z","steps":["trace[164252947] 'read index received' (duration: 122.789195ms)","trace[164252947] 'applied index is now lower than readState.Index' (duration: 390.731µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:57:03.750414Z","caller":"traceutil/trace.go:171","msg":"trace[553917985] linearizableReadLoop","detail":"{readStateIndex:16709; appliedIndex:16708; }","duration":"161.188591ms","start":"2026-10-14T02:57:03.589199Z","end":"2026-10-14T02:57:03.750388Z","steps":["trace[553917985] 'read index received' (duration: 160.948334ms)","trace[553917985] 'applied index is now lower than readState.Index' (duration: 239.097µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:57:03.750626Z","caller":"traceutil/trace.go:171","msg":"trace[1957822814] transaction","detail":"{read_only:false; response_revision:16148; number_of_response:1; }","duration":"169.360021ms","start":"2026-10-14T02:57:03.58118Z","end":"2026-10-14T02:57:03.75054Z","steps":["trace[1957822814] 'process raft request' (duration: 169.035933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:57:03.750929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.629983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gvbfm\" ","response":"range_response_count:1 size:22017"} {"level":"info","ts":"2026-10-14T02:57:03.751014Z","caller":"traceutil/trace.go:171","msg":"trace[1167530554] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gvbfm; range_end:; response_count:1; response_revision:16148; }","duration":"161.830568ms","start":"2026-10-14T02:57:03.589168Z","end":"2026-10-14T02:57:03.750998Z","steps":["trace[1167530554] 'agreement among raft nodes before linearized reading' (duration: 161.352175ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:57:03.75197Z","caller":"traceutil/trace.go:171","msg":"trace[1446128394] transaction","detail":"{read_only:false; response_revision:16149; number_of_response:1; }","duration":"125.678741ms","start":"2026-10-14T02:57:03.626277Z","end":"2026-10-14T02:57:03.751956Z","steps":["trace[1446128394] 'process raft request' (duration: 124.803849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:58:59.032632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14571} {"level":"info","ts":"2026-10-14T02:58:59.116401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14571,"took":"79.876836ms","hash":307664112,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T02:58:59.11648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":307664112,"revision":14571,"compact-revision":12558} {"level":"info","ts":"2026-10-14T03:03:10.509096Z","caller":"traceutil/trace.go:171","msg":"trace[1684105956] transaction","detail":"{read_only:false; response_revision:18870; number_of_response:1; }","duration":"171.559409ms","start":"2026-10-14T03:03:10.337465Z","end":"2026-10-14T03:03:10.509025Z","steps":["trace[1684105956] 'process raft request' (duration: 88.068508ms)","trace[1684105956] 'compare' (duration: 83.170383ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:03:11.43199Z","caller":"traceutil/trace.go:171","msg":"trace[2122430363] linearizableReadLoop","detail":"{readStateIndex:19522; appliedIndex:19520; }","duration":"477.743571ms","start":"2026-10-14T03:03:10.954215Z","end":"2026-10-14T03:03:11.431958Z","steps":["trace[2122430363] 'read index received' (duration: 15.263272ms)","trace[2122430363] 'applied index is now lower than readState.Index' (duration: 462.478779ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:03:11.432076Z","caller":"traceutil/trace.go:171","msg":"trace[1258122039] transaction","detail":"{read_only:false; response_revision:18873; number_of_response:1; }","duration":"487.424509ms","start":"2026-10-14T03:03:10.944614Z","end":"2026-10-14T03:03:11.432038Z","steps":["trace[1258122039] 'process raft request' (duration: 487.151992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.432254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:10.944587Z","time spent":"487.573443ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:03:11.432262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.018959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:03:11.432387Z","caller":"traceutil/trace.go:171","msg":"trace[355522293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18873; }","duration":"478.209933ms","start":"2026-10-14T03:03:10.954146Z","end":"2026-10-14T03:03:11.432356Z","steps":["trace[355522293] 'agreement among raft nodes before linearized reading' (duration: 477.938526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.43244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:10.954128Z","time spent":"478.303236ms","remote":"127.0.0.1:37184","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-10-14T03:03:11.448026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.816876ms","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-10-14T03:03:11.448088Z","caller":"traceutil/trace.go:171","msg":"trace[410850359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18874; }","duration":"109.940088ms","start":"2026-10-14T03:03:11.338134Z","end":"2026-10-14T03:03:11.448074Z","steps":["trace[410850359] 'agreement among raft nodes before linearized reading' (duration: 109.744593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.448103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.054186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gvbfm\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-10-14T03:03:11.44818Z","caller":"traceutil/trace.go:171","msg":"trace[356634662] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gvbfm; range_end:; response_count:1; response_revision:18874; }","duration":"134.16816ms","start":"2026-10-14T03:03:11.31399Z","end":"2026-10-14T03:03:11.448158Z","steps":["trace[356634662] 'agreement among raft nodes before linearized reading' (duration: 133.917443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.448323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.734223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:03:11.448918Z","caller":"traceutil/trace.go:171","msg":"trace[205357865] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18874; }","duration":"356.514983ms","start":"2026-10-14T03:03:11.092375Z","end":"2026-10-14T03:03:11.44889Z","steps":["trace[205357865] 'agreement among raft nodes before linearized reading' (duration: 355.612309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.44899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:11.092346Z","time spent":"356.621086ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":74,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:03:50.406598Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:03:50.420998Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T03:03:50.421161Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T03:03:59.04392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16862} {"level":"info","ts":"2026-10-14T03:03:59.149838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16862,"took":"101.985024ms","hash":2335531099,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":33484800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T03:03:59.149943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2335531099,"revision":16862,"compact-revision":14571} {"level":"info","ts":"2026-10-14T03:08:59.052816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19459} {"level":"info","ts":"2026-10-14T03:08:59.17761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19459,"took":"120.977823ms","hash":684188246,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T03:08:59.177777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":684188246,"revision":19459,"compact-revision":16862} {"level":"info","ts":"2026-10-14T03:08:59.815222Z","caller":"traceutil/trace.go:171","msg":"trace[775265806] transaction","detail":"{read_only:false; response_revision:21621; number_of_response:1; }","duration":"247.91443ms","start":"2026-10-14T03:08:59.567261Z","end":"2026-10-14T03:08:59.815176Z","steps":["trace[775265806] 'process raft request' (duration: 247.456268ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:09:15.28757Z","caller":"traceutil/trace.go:171","msg":"trace[1807744605] transaction","detail":"{read_only:false; response_revision:21702; number_of_response:1; }","duration":"107.946708ms","start":"2026-10-14T03:09:15.179587Z","end":"2026-10-14T03:09:15.287534Z","steps":["trace[1807744605] 'process raft request' (duration: 107.671861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:15.488749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.021196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:09:15.488899Z","caller":"traceutil/trace.go:171","msg":"trace[1326531359] transaction","detail":"{read_only:false; response_revision:21703; number_of_response:1; }","duration":"286.237222ms","start":"2026-10-14T03:09:15.202639Z","end":"2026-10-14T03:09:15.488876Z","steps":["trace[1326531359] 'process raft request' (duration: 162.98386ms)","trace[1326531359] 'compare' (duration: 122.838202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:09:15.7703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.717563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T03:09:15.770514Z","caller":"traceutil/trace.go:171","msg":"trace[2070431281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21703; }","duration":"137.959889ms","start":"2026-10-14T03:09:15.632522Z","end":"2026-10-14T03:09:15.770481Z","steps":["trace[2070431281] 'range keys from in-memory index tree' (duration: 137.512778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.075319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.821138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:09:16.075504Z","caller":"traceutil/trace.go:171","msg":"trace[278483203] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:21705; }","duration":"186.083534ms","start":"2026-10-14T03:09:15.889389Z","end":"2026-10-14T03:09:16.075472Z","steps":["trace[278483203] 'count revisions from in-memory index tree' (duration: 185.677753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.075414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.670656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:09:16.075823Z","caller":"traceutil/trace.go:171","msg":"trace[362858371] linearizableReadLoop","detail":"{readStateIndex:22449; appliedIndex:22448; }","duration":"165.019754ms","start":"2026-10-14T03:09:15.91079Z","end":"2026-10-14T03:09:16.075809Z","steps":["trace[362858371] 'read index received' (duration: 20.753743ms)","trace[362858371] 'applied index is now lower than readState.Index' (duration: 144.264731ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:09:16.076112Z","caller":"traceutil/trace.go:171","msg":"trace[1609257188] transaction","detail":"{read_only:false; response_revision:21706; number_of_response:1; }","duration":"179.053503ms","start":"2026-10-14T03:09:15.89678Z","end":"2026-10-14T03:09:16.075833Z","steps":["trace[1609257188] 'process raft request' (duration: 34.848874ms)","trace[1609257188] 'compare' (duration: 143.548643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:09:16.076203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.097204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-14T03:09:16.076219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.418685ms","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-10-14T03:09:16.076239Z","caller":"traceutil/trace.go:171","msg":"trace[2139424430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21706; }","duration":"129.176555ms","start":"2026-10-14T03:09:15.947049Z","end":"2026-10-14T03:09:16.076225Z","steps":["trace[2139424430] 'agreement among raft nodes before linearized reading' (duration: 129.061492ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:09:16.076276Z","caller":"traceutil/trace.go:171","msg":"trace[1045198427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21706; }","duration":"165.498857ms","start":"2026-10-14T03:09:15.910758Z","end":"2026-10-14T03:09:16.076257Z","steps":["trace[1045198427] 'agreement among raft nodes before linearized reading' (duration: 165.106466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.076358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.126042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:09:16.076455Z","caller":"traceutil/trace.go:171","msg":"trace[687727453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21706; }","duration":"120.318467ms","start":"2026-10-14T03:09:15.956113Z","end":"2026-10-14T03:09:16.076432Z","steps":["trace[687727453] 'agreement among raft nodes before linearized reading' (duration: 120.099471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.076437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.664329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-14T03:09:16.076555Z","caller":"traceutil/trace.go:171","msg":"trace[2120658524] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:21706; }","duration":"110.805543ms","start":"2026-10-14T03:09:15.965719Z","end":"2026-10-14T03:09:16.076524Z","steps":["trace[2120658524] 'agreement among raft nodes before linearized reading' (duration: 110.452974ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:09:16.273936Z","caller":"traceutil/trace.go:171","msg":"trace[1748539873] transaction","detail":"{read_only:false; response_revision:21707; number_of_response:1; }","duration":"192.332345ms","start":"2026-10-14T03:09:16.081561Z","end":"2026-10-14T03:09:16.273893Z","steps":["trace[1748539873] 'process raft request' (duration: 172.492936ms)","trace[1748539873] 'compare' (duration: 19.248314ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:09:16.285662Z","caller":"traceutil/trace.go:171","msg":"trace[991225351] linearizableReadLoop","detail":"{readStateIndex:22451; appliedIndex:22449; }","duration":"177.859562ms","start":"2026-10-14T03:09:16.107768Z","end":"2026-10-14T03:09:16.285628Z","steps":["trace[991225351] 'read index received' (duration: 146.343614ms)","trace[991225351] 'applied index is now lower than readState.Index' (duration: 31.514768ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:09:16.285847Z","caller":"traceutil/trace.go:171","msg":"trace[1141906184] transaction","detail":"{read_only:false; response_revision:21708; number_of_response:1; }","duration":"203.956332ms","start":"2026-10-14T03:09:16.081834Z","end":"2026-10-14T03:09:16.28579Z","steps":["trace[1141906184] 'process raft request' (duration: 203.652985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.286073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.285294ms","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-10-14T03:09:16.286129Z","caller":"traceutil/trace.go:171","msg":"trace[280809173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21708; }","duration":"178.392437ms","start":"2026-10-14T03:09:16.107723Z","end":"2026-10-14T03:09:16.286115Z","steps":["trace[280809173] 'agreement among raft nodes before linearized reading' (duration: 178.18298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:09:16.286209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.715356ms","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-10-14T03:09:16.286344Z","caller":"traceutil/trace.go:171","msg":"trace[284493349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21708; }","duration":"125.926501ms","start":"2026-10-14T03:09:16.160388Z","end":"2026-10-14T03:09:16.286315Z","steps":["trace[284493349] 'agreement among raft nodes before linearized reading' (duration: 125.491089ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:09:16.452527Z","caller":"traceutil/trace.go:171","msg":"trace[712678791] transaction","detail":"{read_only:false; response_revision:21710; number_of_response:1; }","duration":"159.759761ms","start":"2026-10-14T03:09:16.292738Z","end":"2026-10-14T03:09:16.452498Z","steps":["trace[712678791] 'process raft request' (duration: 132.540732ms)","trace[712678791] 'compare' (duration: 27.076855ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:13:30.532485Z","caller":"traceutil/trace.go:171","msg":"trace[439295748] transaction","detail":"{read_only:false; response_revision:24283; number_of_response:1; }","duration":"263.293014ms","start":"2026-10-14T03:13:30.269157Z","end":"2026-10-14T03:13:30.53245Z","steps":["trace[439295748] 'process raft request' (duration: 263.073779ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:13:30.553233Z","caller":"traceutil/trace.go:171","msg":"trace[1828463059] transaction","detail":"{read_only:false; response_revision:24284; number_of_response:1; }","duration":"256.951341ms","start":"2026-10-14T03:13:30.296241Z","end":"2026-10-14T03:13:30.553192Z","steps":["trace[1828463059] 'process raft request' (duration: 255.34753ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:13:59.059973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21617} {"level":"info","ts":"2026-10-14T03:13:59.176898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21617,"took":"112.378663ms","hash":2645981489,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":42508288,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-14T03:13:59.17709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2645981489,"revision":21617,"compact-revision":19459} {"level":"info","ts":"2026-10-14T03:15:44.730633Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000679f.wal"} {"level":"info","ts":"2026-10-14T03:17:10.853625Z","caller":"traceutil/trace.go:171","msg":"trace[590039369] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"174.376653ms","start":"2026-10-14T03:17:10.679216Z","end":"2026-10-14T03:17:10.853592Z","steps":["trace[590039369] 'process raft request' (duration: 174.204698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:11.169321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.529414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:17:11.169632Z","caller":"traceutil/trace.go:171","msg":"trace[1936235549] linearizableReadLoop","detail":"{readStateIndex:27354; appliedIndex:27352; }","duration":"400.228138ms","start":"2026-10-14T03:17:10.769386Z","end":"2026-10-14T03:17:11.169614Z","steps":["trace[1936235549] 'read index received' (duration: 84.148869ms)","trace[1936235549] 'applied index is now lower than readState.Index' (duration: 316.077619ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:11.169797Z","caller":"traceutil/trace.go:171","msg":"trace[2142378633] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"346.449207ms","start":"2026-10-14T03:17:10.823334Z","end":"2026-10-14T03:17:11.169783Z","steps":["trace[2142378633] 'process raft request' (duration: 346.238202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:11.169868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:10.823312Z","time spent":"346.522719ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:11.16994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.552765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:11.169966Z","caller":"traceutil/trace.go:171","msg":"trace[1518300084] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"463.213532ms","start":"2026-10-14T03:17:10.706703Z","end":"2026-10-14T03:17:11.169917Z","steps":["trace[1518300084] 'process raft request' (duration: 292.032201ms)","trace[1518300084] 'compare' (duration: 170.234337ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:11.170036Z","caller":"traceutil/trace.go:171","msg":"trace[2012788777] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"347.263388ms","start":"2026-10-14T03:17:10.822764Z","end":"2026-10-14T03:17:11.170028Z","steps":["trace[2012788777] 'process raft request' (duration: 346.747395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:11.170077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:10.82273Z","time spent":"347.32604ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:11.170084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.202355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:11.170014Z","caller":"traceutil/trace.go:171","msg":"trace[2036590767] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26476; }","duration":"400.662368ms","start":"2026-10-14T03:17:10.769336Z","end":"2026-10-14T03:17:11.169998Z","steps":["trace[2036590767] 'agreement among raft nodes before linearized reading' (duration: 400.454183ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:11.17019Z","caller":"traceutil/trace.go:171","msg":"trace[861597645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26476; }","duration":"214.363889ms","start":"2026-10-14T03:17:10.955801Z","end":"2026-10-14T03:17:11.170165Z","steps":["trace[861597645] 'agreement among raft nodes before linearized reading' (duration: 214.208865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:11.170201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:10.706682Z","time spent":"463.386427ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:11.170222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:10.769319Z","time spent":"400.889844ms","remote":"127.0.0.1:36484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:17:11.547806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.112532ms","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-10-14T03:17:11.547891Z","caller":"traceutil/trace.go:171","msg":"trace[434747750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26476; }","duration":"251.259325ms","start":"2026-10-14T03:17:11.296614Z","end":"2026-10-14T03:17:11.547873Z","steps":["trace[434747750] 'range keys from in-memory index tree' (duration: 250.847514ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:11.700959Z","caller":"traceutil/trace.go:171","msg":"trace[601493999] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"135.940128ms","start":"2026-10-14T03:17:11.56498Z","end":"2026-10-14T03:17:11.70092Z","steps":["trace[601493999] 'process raft request' (duration: 135.684502ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:11.729634Z","caller":"traceutil/trace.go:171","msg":"trace[567276488] linearizableReadLoop","detail":"{readStateIndex:27359; appliedIndex:27356; }","duration":"143.535591ms","start":"2026-10-14T03:17:11.586069Z","end":"2026-10-14T03:17:11.729605Z","steps":["trace[567276488] 'read index received' (duration: 114.631841ms)","trace[567276488] 'applied index is now lower than readState.Index' (duration: 28.90213ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:11.729693Z","caller":"traceutil/trace.go:171","msg":"trace[37428620] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"162.121039ms","start":"2026-10-14T03:17:11.567536Z","end":"2026-10-14T03:17:11.729657Z","steps":["trace[37428620] 'process raft request' (duration: 162.028276ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:11.729702Z","caller":"traceutil/trace.go:171","msg":"trace[1211716161] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"163.290478ms","start":"2026-10-14T03:17:11.566386Z","end":"2026-10-14T03:17:11.729676Z","steps":["trace[1211716161] 'process raft request' (duration: 163.019231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:11.730029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.956502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T03:17:11.730137Z","caller":"traceutil/trace.go:171","msg":"trace[198922031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26479; }","duration":"144.118086ms","start":"2026-10-14T03:17:11.585989Z","end":"2026-10-14T03:17:11.730107Z","steps":["trace[198922031] 'agreement among raft nodes before linearized reading' (duration: 143.777458ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:14.03056Z","caller":"traceutil/trace.go:171","msg":"trace[2105245916] linearizableReadLoop","detail":"{readStateIndex:27383; appliedIndex:27382; }","duration":"102.325836ms","start":"2026-10-14T03:17:13.928202Z","end":"2026-10-14T03:17:14.030527Z","steps":["trace[2105245916] 'read index received' (duration: 88.112232ms)","trace[2105245916] 'applied index is now lower than readState.Index' (duration: 14.212514ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:14.031016Z","caller":"traceutil/trace.go:171","msg":"trace[459354686] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"107.398247ms","start":"2026-10-14T03:17:13.923598Z","end":"2026-10-14T03:17:14.030997Z","steps":["trace[459354686] 'process raft request' (duration: 92.846373ms)","trace[459354686] 'compare' (duration: 13.922537ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:14.031312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.084986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T03:17:14.031356Z","caller":"traceutil/trace.go:171","msg":"trace[542404790] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26502; }","duration":"103.178118ms","start":"2026-10-14T03:17:13.928166Z","end":"2026-10-14T03:17:14.031344Z","steps":["trace[542404790] 'agreement among raft nodes before linearized reading' (duration: 102.996123ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:15.865559Z","caller":"traceutil/trace.go:171","msg":"trace[2128294617] linearizableReadLoop","detail":"{readStateIndex:27394; appliedIndex:27393; }","duration":"121.469368ms","start":"2026-10-14T03:17:15.744063Z","end":"2026-10-14T03:17:15.865533Z","steps":["trace[2128294617] 'read index received' (duration: 121.238422ms)","trace[2128294617] 'applied index is now lower than readState.Index' (duration: 229.616µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:15.866049Z","caller":"traceutil/trace.go:171","msg":"trace[382944939] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"146.799069ms","start":"2026-10-14T03:17:15.719182Z","end":"2026-10-14T03:17:15.865981Z","steps":["trace[382944939] 'process raft request' (duration: 146.186942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:15.866518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.426273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:17:15.8666Z","caller":"traceutil/trace.go:171","msg":"trace[1579738773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26513; }","duration":"122.544315ms","start":"2026-10-14T03:17:15.744039Z","end":"2026-10-14T03:17:15.866583Z","steps":["trace[1579738773] 'agreement among raft nodes before linearized reading' (duration: 121.92758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.664676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.851706ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:16.664846Z","caller":"traceutil/trace.go:171","msg":"trace[1613518495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26513; }","duration":"531.052411ms","start":"2026-10-14T03:17:16.133768Z","end":"2026-10-14T03:17:16.664821Z","steps":["trace[1613518495] 'range keys from in-memory index tree' (duration: 530.825954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.666014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.928761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:17:16.666236Z","caller":"traceutil/trace.go:171","msg":"trace[1089935928] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"881.418102ms","start":"2026-10-14T03:17:15.784746Z","end":"2026-10-14T03:17:16.666164Z","steps":["trace[1089935928] 'process raft request' (duration: 330.06239ms)","trace[1089935928] 'compare' (duration: 550.027818ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:16.666295Z","caller":"traceutil/trace.go:171","msg":"trace[1364526079] linearizableReadLoop","detail":"{readStateIndex:27395; appliedIndex:27394; }","duration":"800.621639ms","start":"2026-10-14T03:17:15.86564Z","end":"2026-10-14T03:17:16.666262Z","steps":["trace[1364526079] 'read index received' (duration: 249.186086ms)","trace[1364526079] 'applied index is now lower than readState.Index' (duration: 551.432543ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:16.666333Z","caller":"traceutil/trace.go:171","msg":"trace[1590164951] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"794.355588ms","start":"2026-10-14T03:17:15.871953Z","end":"2026-10-14T03:17:16.666309Z","steps":["trace[1590164951] 'process raft request' (duration: 794.196194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.666454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.784729Z","time spent":"881.602667ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:16.66646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.871918Z","time spent":"794.46348ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:16.666681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.235994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:16.666751Z","caller":"traceutil/trace.go:171","msg":"trace[1204896470] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"560.851865ms","start":"2026-10-14T03:17:16.105837Z","end":"2026-10-14T03:17:16.666689Z","steps":["trace[1204896470] 'process raft request' (duration: 560.419874ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:16.666829Z","caller":"traceutil/trace.go:171","msg":"trace[2050975963] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26516; }","duration":"845.430768ms","start":"2026-10-14T03:17:15.821372Z","end":"2026-10-14T03:17:16.666803Z","steps":["trace[2050975963] 'agreement among raft nodes before linearized reading' (duration: 844.892464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.666895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.821359Z","time spent":"845.515561ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:17:16.666956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:16.105807Z","time spent":"561.047509ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:16.666976Z","caller":"traceutil/trace.go:171","msg":"trace[571824978] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"406.221637ms","start":"2026-10-14T03:17:16.260737Z","end":"2026-10-14T03:17:16.666959Z","steps":["trace[571824978] 'process raft request' (duration: 405.819866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.667087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:16.260686Z","time spent":"406.324379ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:16.667012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.819854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:17:16.667214Z","caller":"traceutil/trace.go:171","msg":"trace[934484217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26516; }","duration":"891.243086ms","start":"2026-10-14T03:17:15.775947Z","end":"2026-10-14T03:17:16.667139Z","steps":["trace[934484217] 'agreement among raft nodes before linearized reading' (duration: 890.415604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.667273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.775924Z","time spent":"891.331358ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T03:17:16.667557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.521011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T03:17:16.66773Z","caller":"traceutil/trace.go:171","msg":"trace[193209083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26516; }","duration":"882.735126ms","start":"2026-10-14T03:17:15.784945Z","end":"2026-10-14T03:17:16.66768Z","steps":["trace[193209083] 'agreement among raft nodes before linearized reading' (duration: 881.33715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.66779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.784939Z","time spent":"882.835079ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T03:17:16.669354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.865131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:16.669418Z","caller":"traceutil/trace.go:171","msg":"trace[733814334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26520; }","duration":"713.932403ms","start":"2026-10-14T03:17:15.955471Z","end":"2026-10-14T03:17:16.669404Z","steps":["trace[733814334] 'agreement among raft nodes before linearized reading' (duration: 713.79825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.669454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.955362Z","time spent":"714.085318ms","remote":"127.0.0.1:37188","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-10-14T03:17:16.669645Z","caller":"traceutil/trace.go:171","msg":"trace[1422460517] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"284.722987ms","start":"2026-10-14T03:17:16.384893Z","end":"2026-10-14T03:17:16.669616Z","steps":["trace[1422460517] 'process raft request' (duration: 284.112852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.66974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.56649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:16.669787Z","caller":"traceutil/trace.go:171","msg":"trace[1490421798] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26520; }","duration":"453.643553ms","start":"2026-10-14T03:17:16.216131Z","end":"2026-10-14T03:17:16.669775Z","steps":["trace[1490421798] 'agreement among raft nodes before linearized reading' (duration: 453.54127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.66982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:16.21612Z","time spent":"453.688155ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:17:16.669849Z","caller":"traceutil/trace.go:171","msg":"trace[1106621720] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"148.901773ms","start":"2026-10-14T03:17:16.520909Z","end":"2026-10-14T03:17:16.669811Z","steps":["trace[1106621720] 'process raft request' (duration: 148.316477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.669653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.687601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T03:17:16.669997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.396676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T03:17:16.670013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.823382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-14T03:17:16.670002Z","caller":"traceutil/trace.go:171","msg":"trace[1202590027] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26520; }","duration":"171.05801ms","start":"2026-10-14T03:17:16.498921Z","end":"2026-10-14T03:17:16.669979Z","steps":["trace[1202590027] 'agreement among raft nodes before linearized reading' (duration: 170.66821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:16.670082Z","caller":"traceutil/trace.go:171","msg":"trace[830313176] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26520; }","duration":"654.020137ms","start":"2026-10-14T03:17:16.016038Z","end":"2026-10-14T03:17:16.670058Z","steps":["trace[830313176] 'agreement among raft nodes before linearized reading' (duration: 653.826613ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:16.670039Z","caller":"traceutil/trace.go:171","msg":"trace[506448063] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:26520; }","duration":"675.495409ms","start":"2026-10-14T03:17:15.994531Z","end":"2026-10-14T03:17:16.670026Z","steps":["trace[506448063] 'agreement among raft nodes before linearized reading' (duration: 675.431637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.670144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:16.01601Z","time spent":"654.125759ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-10-14T03:17:16.670204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:15.994499Z","time spent":"675.655652ms","remote":"127.0.0.1:51364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:17:16.67025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.342309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:16.67031Z","caller":"traceutil/trace.go:171","msg":"trace[312252664] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26520; }","duration":"480.420491ms","start":"2026-10-14T03:17:16.189872Z","end":"2026-10-14T03:17:16.670293Z","steps":["trace[312252664] 'agreement among raft nodes before linearized reading' (duration: 480.029571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:16.670352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:16.189742Z","time spent":"480.600556ms","remote":"127.0.0.1:51474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:17:16.669875Z","caller":"traceutil/trace.go:171","msg":"trace[1978423540] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"176.231063ms","start":"2026-10-14T03:17:16.493591Z","end":"2026-10-14T03:17:16.669822Z","steps":["trace[1978423540] 'process raft request' (duration: 175.568216ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:16.85843Z","caller":"traceutil/trace.go:171","msg":"trace[1041241059] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"181.836838ms","start":"2026-10-14T03:17:16.67655Z","end":"2026-10-14T03:17:16.858387Z","steps":["trace[1041241059] 'process raft request' (duration: 167.031288ms)","trace[1041241059] 'compare' (duration: 14.396639ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:17.115338Z","caller":"traceutil/trace.go:171","msg":"trace[775133555] linearizableReadLoop","detail":"{readStateIndex:27404; appliedIndex:27403; }","duration":"159.443662ms","start":"2026-10-14T03:17:16.955869Z","end":"2026-10-14T03:17:17.115313Z","steps":["trace[775133555] 'read index received' (duration: 159.065842ms)","trace[775133555] 'applied index is now lower than readState.Index' (duration: 377µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:17.115461Z","caller":"traceutil/trace.go:171","msg":"trace[2107176257] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"227.90821ms","start":"2026-10-14T03:17:16.887517Z","end":"2026-10-14T03:17:17.115425Z","steps":["trace[2107176257] 'process raft request' (duration: 227.514709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:17.115631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.755441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:17.115664Z","caller":"traceutil/trace.go:171","msg":"trace[1165280233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26523; }","duration":"159.864323ms","start":"2026-10-14T03:17:16.955791Z","end":"2026-10-14T03:17:17.115655Z","steps":["trace[1165280233] 'agreement among raft nodes before linearized reading' (duration: 159.660718ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.219414Z","caller":"traceutil/trace.go:171","msg":"trace[1137629344] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27404; }","duration":"103.930288ms","start":"2026-10-14T03:17:17.115428Z","end":"2026-10-14T03:17:17.219358Z","steps":["trace[1137629344] 'read index received' (duration: 100.844528ms)","trace[1137629344] 'applied index is now lower than readState.Index' (duration: 3.0842ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:17.219596Z","caller":"traceutil/trace.go:171","msg":"trace[1949528124] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"252.150661ms","start":"2026-10-14T03:17:16.967433Z","end":"2026-10-14T03:17:17.219584Z","steps":["trace[1949528124] 'process raft request' (duration: 248.883407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:17.219639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.431519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:17.219727Z","caller":"traceutil/trace.go:171","msg":"trace[1329445049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26525; }","duration":"102.578583ms","start":"2026-10-14T03:17:17.117113Z","end":"2026-10-14T03:17:17.219692Z","steps":["trace[1329445049] 'agreement among raft nodes before linearized reading' (duration: 102.45726ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.219747Z","caller":"traceutil/trace.go:171","msg":"trace[1499935452] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"148.966213ms","start":"2026-10-14T03:17:17.070737Z","end":"2026-10-14T03:17:17.219703Z","steps":["trace[1499935452] 'process raft request' (duration: 148.554293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:17.219829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.499878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-78df85dcdb-bnxgv.18de4561e21ce05d\" ","response":"range_response_count:1 size:2096"} {"level":"info","ts":"2026-10-14T03:17:17.219882Z","caller":"traceutil/trace.go:171","msg":"trace[165781701] range","detail":"{range_begin:/registry/events/openstack/glance-api-78df85dcdb-bnxgv.18de4561e21ce05d; range_end:; response_count:1; response_revision:26525; }","duration":"149.61163ms","start":"2026-10-14T03:17:17.070251Z","end":"2026-10-14T03:17:17.219863Z","steps":["trace[165781701] 'agreement among raft nodes before linearized reading' (duration: 149.383585ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.384465Z","caller":"traceutil/trace.go:171","msg":"trace[2104003406] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"146.920962ms","start":"2026-10-14T03:17:17.237514Z","end":"2026-10-14T03:17:17.384435Z","steps":["trace[2104003406] 'process raft request' (duration: 146.8648ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.384575Z","caller":"traceutil/trace.go:171","msg":"trace[1575299933] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"162.385608ms","start":"2026-10-14T03:17:17.222151Z","end":"2026-10-14T03:17:17.384536Z","steps":["trace[1575299933] 'process raft request' (duration: 161.965098ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.384805Z","caller":"traceutil/trace.go:171","msg":"trace[163918603] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"150.413371ms","start":"2026-10-14T03:17:17.234347Z","end":"2026-10-14T03:17:17.38476Z","steps":["trace[163918603] 'process raft request' (duration: 149.96436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:17.69956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.95414ms","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:6"} {"level":"info","ts":"2026-10-14T03:17:17.699682Z","caller":"traceutil/trace.go:171","msg":"trace[1630296390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26529; }","duration":"166.143474ms","start":"2026-10-14T03:17:17.533505Z","end":"2026-10-14T03:17:17.699648Z","steps":["trace[1630296390] 'count revisions from in-memory index tree' (duration: 165.836146ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.854099Z","caller":"traceutil/trace.go:171","msg":"trace[175879900] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"145.533355ms","start":"2026-10-14T03:17:17.708528Z","end":"2026-10-14T03:17:17.854061Z","steps":["trace[175879900] 'process raft request' (duration: 145.285719ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:18.124031Z","caller":"traceutil/trace.go:171","msg":"trace[857088358] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"415.454374ms","start":"2026-10-14T03:17:17.708537Z","end":"2026-10-14T03:17:18.123991Z","steps":["trace[857088358] 'process raft request' (duration: 411.43799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:18.124532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:17.708512Z","time spent":"415.793362ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":865,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:18.130602Z","caller":"traceutil/trace.go:171","msg":"trace[191945446] linearizableReadLoop","detail":"{readStateIndex:27413; appliedIndex:27410; }","duration":"306.147628ms","start":"2026-10-14T03:17:17.824429Z","end":"2026-10-14T03:17:18.130576Z","steps":["trace[191945446] 'read index received' (duration: 28.717867ms)","trace[191945446] 'applied index is now lower than readState.Index' (duration: 277.428231ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:18.13079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.338202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:18.130814Z","caller":"traceutil/trace.go:171","msg":"trace[1017352280] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26531; }","duration":"306.437444ms","start":"2026-10-14T03:17:17.824367Z","end":"2026-10-14T03:17:18.130805Z","steps":["trace[1017352280] 'agreement among raft nodes before linearized reading' (duration: 306.354932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:18.130838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:17.824344Z","time spent":"306.485986ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:17:18.13104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.42671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18de470caac23424\" ","response":"range_response_count:1 size:722"} {"level":"warn","ts":"2026-10-14T03:17:18.13116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.558651ms","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-10-14T03:17:18.131221Z","caller":"traceutil/trace.go:171","msg":"trace[133770935] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18de470caac23424; range_end:; response_count:1; response_revision:26531; }","duration":"270.579234ms","start":"2026-10-14T03:17:17.860521Z","end":"2026-10-14T03:17:18.131101Z","steps":["trace[133770935] 'agreement among raft nodes before linearized reading' (duration: 270.257396ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:18.13126Z","caller":"traceutil/trace.go:171","msg":"trace[699629066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26531; }","duration":"259.708376ms","start":"2026-10-14T03:17:17.871525Z","end":"2026-10-14T03:17:18.131233Z","steps":["trace[699629066] 'agreement among raft nodes before linearized reading' (duration: 259.44737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:18.448204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.477031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:18.448329Z","caller":"traceutil/trace.go:171","msg":"trace[742177407] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26534; }","duration":"118.700636ms","start":"2026-10-14T03:17:18.329603Z","end":"2026-10-14T03:17:18.448304Z","steps":["trace[742177407] 'count revisions from in-memory index tree' (duration: 118.361248ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:18.936245Z","caller":"traceutil/trace.go:171","msg":"trace[1769673401] linearizableReadLoop","detail":"{readStateIndex:27417; appliedIndex:27416; }","duration":"262.846407ms","start":"2026-10-14T03:17:18.673371Z","end":"2026-10-14T03:17:18.936217Z","steps":["trace[1769673401] 'read index received' (duration: 56.992µs)","trace[1769673401] 'applied index is now lower than readState.Index' (duration: 262.787875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:18.936523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.135824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:17:18.936508Z","caller":"traceutil/trace.go:171","msg":"trace[2133917988] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"263.128304ms","start":"2026-10-14T03:17:18.673324Z","end":"2026-10-14T03:17:18.936452Z","steps":["trace[2133917988] 'process raft request' (duration: 262.715222ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:18.936563Z","caller":"traceutil/trace.go:171","msg":"trace[2125647982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26535; }","duration":"263.237676ms","start":"2026-10-14T03:17:18.673315Z","end":"2026-10-14T03:17:18.936553Z","steps":["trace[2125647982] 'agreement among raft nodes before linearized reading' (duration: 263.076632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.162171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.642573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:17:19.162593Z","caller":"traceutil/trace.go:171","msg":"trace[1867454824] linearizableReadLoop","detail":"{readStateIndex:27421; appliedIndex:27417; }","duration":"226.207045ms","start":"2026-10-14T03:17:18.936359Z","end":"2026-10-14T03:17:19.162566Z","steps":["trace[1867454824] 'read index received' (duration: 58.050309ms)","trace[1867454824] 'applied index is now lower than readState.Index' (duration: 168.155096ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:19.162827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.737426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-14T03:17:19.162834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.970903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T03:17:19.162925Z","caller":"traceutil/trace.go:171","msg":"trace[580971000] transaction","detail":"{read_only:false; response_revision:26536; number_of_response:1; }","duration":"487.731108ms","start":"2026-10-14T03:17:18.674843Z","end":"2026-10-14T03:17:19.162574Z","steps":["trace[580971000] 'process raft request' (duration: 319.533501ms)","trace[580971000] 'compare' (duration: 167.448638ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:19.163008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.67482Z","time spent":"488.145088ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:19.163091Z","caller":"traceutil/trace.go:171","msg":"trace[2077089257] transaction","detail":"{read_only:false; response_revision:26538; number_of_response:1; }","duration":"486.193549ms","start":"2026-10-14T03:17:18.676418Z","end":"2026-10-14T03:17:19.162612Z","steps":["trace[2077089257] 'process raft request' (duration: 486.027244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.163218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.676376Z","time spent":"486.788575ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:19.163326Z","caller":"traceutil/trace.go:171","msg":"trace[226274231] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"487.256666ms","start":"2026-10-14T03:17:18.675367Z","end":"2026-10-14T03:17:19.162623Z","steps":["trace[226274231] 'process raft request' (duration: 486.972619ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:19.163501Z","caller":"traceutil/trace.go:171","msg":"trace[1360731221] transaction","detail":"{read_only:false; response_revision:26539; number_of_response:1; }","duration":"485.244534ms","start":"2026-10-14T03:17:18.677383Z","end":"2026-10-14T03:17:19.162628Z","steps":["trace[1360731221] 'process raft request' (duration: 485.120561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.163696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.675358Z","time spent":"488.16298ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:19.16376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.677374Z","time spent":"486.209199ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:19.163786Z","caller":"traceutil/trace.go:171","msg":"trace[1897528879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26539; }","duration":"400.838608ms","start":"2026-10-14T03:17:18.76203Z","end":"2026-10-14T03:17:19.162869Z","steps":["trace[1897528879] 'agreement among raft nodes before linearized reading' (duration: 400.602002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.164102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.762006Z","time spent":"402.027459ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T03:17:19.164326Z","caller":"traceutil/trace.go:171","msg":"trace[1879402889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26539; }","duration":"299.083475ms","start":"2026-10-14T03:17:18.863797Z","end":"2026-10-14T03:17:19.162881Z","steps":["trace[1879402889] 'agreement among raft nodes before linearized reading' (duration: 298.826479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.164404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:18.863775Z","time spent":"300.597405ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T03:17:19.175727Z","caller":"traceutil/trace.go:171","msg":"trace[994652045] transaction","detail":"{read_only:false; response_revision:26540; number_of_response:1; }","duration":"233.571804ms","start":"2026-10-14T03:17:18.942105Z","end":"2026-10-14T03:17:19.175677Z","steps":["trace[994652045] 'process raft request' (duration: 233.371009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.17575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.641389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:19.175803Z","caller":"traceutil/trace.go:171","msg":"trace[510758469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26540; }","duration":"221.727901ms","start":"2026-10-14T03:17:18.954061Z","end":"2026-10-14T03:17:19.175788Z","steps":["trace[510758469] 'agreement among raft nodes before linearized reading' (duration: 221.563457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:19.176228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.186563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T03:17:19.176323Z","caller":"traceutil/trace.go:171","msg":"trace[1684607688] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26540; }","duration":"125.363857ms","start":"2026-10-14T03:17:19.050935Z","end":"2026-10-14T03:17:19.176299Z","steps":["trace[1684607688] 'agreement among raft nodes before linearized reading' (duration: 125.047639ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:19.426774Z","caller":"traceutil/trace.go:171","msg":"trace[1303872710] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"157.873843ms","start":"2026-10-14T03:17:19.268858Z","end":"2026-10-14T03:17:19.426732Z","steps":["trace[1303872710] 'process raft request' (duration: 72.124382ms)","trace[1303872710] 'compare' (duration: 85.560296ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:19.509783Z","caller":"traceutil/trace.go:171","msg":"trace[679941051] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"118.799819ms","start":"2026-10-14T03:17:19.390941Z","end":"2026-10-14T03:17:19.50974Z","steps":["trace[679941051] 'process raft request' (duration: 118.486121ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:19.510024Z","caller":"traceutil/trace.go:171","msg":"trace[951134083] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"118.138952ms","start":"2026-10-14T03:17:19.391851Z","end":"2026-10-14T03:17:19.50999Z","steps":["trace[951134083] 'process raft request' (duration: 117.809274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:35.991155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.8146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:17:35.991502Z","caller":"traceutil/trace.go:171","msg":"trace[407047518] linearizableReadLoop","detail":"{readStateIndex:27609; appliedIndex:27607; }","duration":"295.286229ms","start":"2026-10-14T03:17:35.69619Z","end":"2026-10-14T03:17:35.991477Z","steps":["trace[407047518] 'read index received' (duration: 31.712214ms)","trace[407047518] 'applied index is now lower than readState.Index' (duration: 263.572435ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:35.991621Z","caller":"traceutil/trace.go:171","msg":"trace[241936203] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"410.056585ms","start":"2026-10-14T03:17:35.581547Z","end":"2026-10-14T03:17:35.991604Z","steps":["trace[241936203] 'process raft request' (duration: 146.335616ms)","trace[241936203] 'compare' (duration: 261.308127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:35.991695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:35.581512Z","time spent":"410.145047ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:35.992451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.971534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T03:17:35.992512Z","caller":"traceutil/trace.go:171","msg":"trace[1771517610] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26724; }","duration":"285.043276ms","start":"2026-10-14T03:17:35.707453Z","end":"2026-10-14T03:17:35.992496Z","steps":["trace[1771517610] 'agreement among raft nodes before linearized reading' (duration: 284.764269ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:35.992745Z","caller":"traceutil/trace.go:171","msg":"trace[794824627] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"410.484055ms","start":"2026-10-14T03:17:35.582246Z","end":"2026-10-14T03:17:35.99273Z","steps":["trace[794824627] 'process raft request' (duration: 409.060409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:35.992826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:35.58221Z","time spent":"410.572947ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:35.993114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.920001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2ea67b5-1489-42bf-a57b-2ee246d25b35\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-10-14T03:17:35.995598Z","caller":"traceutil/trace.go:171","msg":"trace[610614622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2ea67b5-1489-42bf-a57b-2ee246d25b35; range_end:; response_count:1; response_revision:26724; }","duration":"299.434376ms","start":"2026-10-14T03:17:35.696143Z","end":"2026-10-14T03:17:35.995577Z","steps":["trace[610614622] 'agreement among raft nodes before linearized reading' (duration: 296.823899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:36.253766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.582465ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:36.253913Z","caller":"traceutil/trace.go:171","msg":"trace[358851270] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26724; }","duration":"120.744909ms","start":"2026-10-14T03:17:36.133143Z","end":"2026-10-14T03:17:36.253888Z","steps":["trace[358851270] 'range keys from in-memory index tree' (duration: 120.542064ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:36.254745Z","caller":"traceutil/trace.go:171","msg":"trace[1831262673] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"250.569532ms","start":"2026-10-14T03:17:36.004095Z","end":"2026-10-14T03:17:36.254664Z","steps":["trace[1831262673] 'process raft request' (duration: 212.054873ms)","trace[1831262673] 'compare' (duration: 38.001165ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:36.971368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.400431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:36.971515Z","caller":"traceutil/trace.go:171","msg":"trace[1473682682] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26727; }","duration":"145.619027ms","start":"2026-10-14T03:17:36.825871Z","end":"2026-10-14T03:17:36.97149Z","steps":["trace[1473682682] 'count revisions from in-memory index tree' (duration: 145.286849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.330316Z","caller":"traceutil/trace.go:171","msg":"trace[460959656] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"297.6182ms","start":"2026-10-14T03:17:37.032667Z","end":"2026-10-14T03:17:37.330285Z","steps":["trace[460959656] 'process raft request' (duration: 297.390854ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.52553Z","caller":"traceutil/trace.go:171","msg":"trace[774836899] transaction","detail":"{read_only:false; response_revision:26730; number_of_response:1; }","duration":"236.989002ms","start":"2026-10-14T03:17:37.288512Z","end":"2026-10-14T03:17:37.525501Z","steps":["trace[774836899] 'process raft request' (duration: 236.864688ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.525975Z","caller":"traceutil/trace.go:171","msg":"trace[2059578504] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"255.128808ms","start":"2026-10-14T03:17:37.27061Z","end":"2026-10-14T03:17:37.525739Z","steps":["trace[2059578504] 'process raft request' (duration: 254.577364ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.526159Z","caller":"traceutil/trace.go:171","msg":"trace[1539071573] transaction","detail":"{read_only:false; response_revision:26731; number_of_response:1; }","duration":"230.641241ms","start":"2026-10-14T03:17:37.295451Z","end":"2026-10-14T03:17:37.526092Z","steps":["trace[1539071573] 'process raft request' (duration: 229.976983ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.526219Z","caller":"traceutil/trace.go:171","msg":"trace[1732116867] linearizableReadLoop","detail":"{readStateIndex:27618; appliedIndex:27612; }","duration":"207.146356ms","start":"2026-10-14T03:17:37.319049Z","end":"2026-10-14T03:17:37.526195Z","steps":["trace[1732116867] 'read index received' (duration: 11.031963ms)","trace[1732116867] 'applied index is now lower than readState.Index' (duration: 196.112993ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:37.526325Z","caller":"traceutil/trace.go:171","msg":"trace[453891974] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"227.88705ms","start":"2026-10-14T03:17:37.298366Z","end":"2026-10-14T03:17:37.526253Z","steps":["trace[453891974] 'process raft request' (duration: 227.609632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:37.526513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.448764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-14T03:17:37.526541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.584935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T03:17:37.526588Z","caller":"traceutil/trace.go:171","msg":"trace[812123108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26733; }","duration":"207.577518ms","start":"2026-10-14T03:17:37.318991Z","end":"2026-10-14T03:17:37.526568Z","steps":["trace[812123108] 'agreement among raft nodes before linearized reading' (duration: 207.351522ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.526594Z","caller":"traceutil/trace.go:171","msg":"trace[38701455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26733; }","duration":"203.672288ms","start":"2026-10-14T03:17:37.322908Z","end":"2026-10-14T03:17:37.52658Z","steps":["trace[38701455] 'agreement among raft nodes before linearized reading' (duration: 203.483563ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:37.52624Z","caller":"traceutil/trace.go:171","msg":"trace[1303685080] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"217.756089ms","start":"2026-10-14T03:17:37.30847Z","end":"2026-10-14T03:17:37.526226Z","steps":["trace[1303685080] 'process raft request' (duration: 217.652396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:37.527216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.216361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:17:37.527332Z","caller":"traceutil/trace.go:171","msg":"trace[603781015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26733; }","duration":"204.350215ms","start":"2026-10-14T03:17:37.322953Z","end":"2026-10-14T03:17:37.527303Z","steps":["trace[603781015] 'agreement among raft nodes before linearized reading' (duration: 204.052638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:37.527343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.225591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:17:37.527473Z","caller":"traceutil/trace.go:171","msg":"trace[862026325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26733; }","duration":"204.423807ms","start":"2026-10-14T03:17:37.323031Z","end":"2026-10-14T03:17:37.527455Z","steps":["trace[862026325] 'agreement among raft nodes before linearized reading' (duration: 204.188471ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:38.064508Z","caller":"traceutil/trace.go:171","msg":"trace[983731872] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27623; }","duration":"109.49485ms","start":"2026-10-14T03:17:37.954973Z","end":"2026-10-14T03:17:38.064468Z","steps":["trace[983731872] 'read index received' (duration: 76.36656ms)","trace[983731872] 'applied index is now lower than readState.Index' (duration: 33.12603ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:38.064769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.764817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:38.064812Z","caller":"traceutil/trace.go:171","msg":"trace[1508946151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26738; }","duration":"109.87774ms","start":"2026-10-14T03:17:37.954923Z","end":"2026-10-14T03:17:38.0648Z","steps":["trace[1508946151] 'agreement among raft nodes before linearized reading' (duration: 109.734256ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:38.215349Z","caller":"traceutil/trace.go:171","msg":"trace[307795198] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"215.984414ms","start":"2026-10-14T03:17:37.999333Z","end":"2026-10-14T03:17:38.215317Z","steps":["trace[307795198] 'process raft request' (duration: 215.677525ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:38.215814Z","caller":"traceutil/trace.go:171","msg":"trace[1098803892] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"215.4629ms","start":"2026-10-14T03:17:38.000305Z","end":"2026-10-14T03:17:38.215768Z","steps":["trace[1098803892] 'process raft request' (duration: 214.942806ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:38.215773Z","caller":"traceutil/trace.go:171","msg":"trace[1768187096] linearizableReadLoop","detail":"{readStateIndex:27626; appliedIndex:27624; }","duration":"151.044986ms","start":"2026-10-14T03:17:38.064597Z","end":"2026-10-14T03:17:38.215642Z","steps":["trace[1768187096] 'read index received' (duration: 150.369998ms)","trace[1768187096] 'applied index is now lower than readState.Index' (duration: 665.548µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:38.215965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.260592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-14T03:17:38.216023Z","caller":"traceutil/trace.go:171","msg":"trace[190778114] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26740; }","duration":"174.371585ms","start":"2026-10-14T03:17:38.041634Z","end":"2026-10-14T03:17:38.216006Z","steps":["trace[190778114] 'agreement among raft nodes before linearized reading' (duration: 174.20003ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:38.468059Z","caller":"traceutil/trace.go:171","msg":"trace[573010167] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"141.192324ms","start":"2026-10-14T03:17:38.326835Z","end":"2026-10-14T03:17:38.468028Z","steps":["trace[573010167] 'process raft request' (duration: 140.971459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.250232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.237688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-14T03:17:39.250323Z","caller":"traceutil/trace.go:171","msg":"trace[1945790934] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26742; }","duration":"108.382822ms","start":"2026-10-14T03:17:39.141916Z","end":"2026-10-14T03:17:39.250299Z","steps":["trace[1945790934] 'range keys from in-memory index tree' (duration: 108.036973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.251315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.195609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-14T03:17:39.251548Z","caller":"traceutil/trace.go:171","msg":"trace[1409465572] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26742; }","duration":"203.02727ms","start":"2026-10-14T03:17:39.048492Z","end":"2026-10-14T03:17:39.251519Z","steps":["trace[1409465572] 'range keys from in-memory index tree' (duration: 201.957073ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.684308Z","caller":"traceutil/trace.go:171","msg":"trace[2023965901] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"347.92907ms","start":"2026-10-14T03:17:39.33634Z","end":"2026-10-14T03:17:39.684269Z","steps":["trace[2023965901] 'process raft request' (duration: 347.612362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.684501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:39.33631Z","time spent":"348.089233ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:39.709853Z","caller":"traceutil/trace.go:171","msg":"trace[1210529564] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"175.511235ms","start":"2026-10-14T03:17:39.53431Z","end":"2026-10-14T03:17:39.709821Z","steps":["trace[1210529564] 'process raft request' (duration: 175.279689ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.710151Z","caller":"traceutil/trace.go:171","msg":"trace[317106015] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"175.286649ms","start":"2026-10-14T03:17:39.534853Z","end":"2026-10-14T03:17:39.710139Z","steps":["trace[317106015] 'process raft request' (duration: 174.887459ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.710294Z","caller":"traceutil/trace.go:171","msg":"trace[2128635209] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"174.775165ms","start":"2026-10-14T03:17:39.53551Z","end":"2026-10-14T03:17:39.710285Z","steps":["trace[2128635209] 'process raft request' (duration: 174.516769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.710377Z","caller":"traceutil/trace.go:171","msg":"trace[557222783] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"173.252637ms","start":"2026-10-14T03:17:39.537115Z","end":"2026-10-14T03:17:39.710368Z","steps":["trace[557222783] 'process raft request' (duration: 173.090902ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.710385Z","caller":"traceutil/trace.go:171","msg":"trace[1206882858] linearizableReadLoop","detail":"{readStateIndex:27633; appliedIndex:27628; }","duration":"146.255143ms","start":"2026-10-14T03:17:39.564095Z","end":"2026-10-14T03:17:39.71035Z","steps":["trace[1206882858] 'read index received' (duration: 120.138333ms)","trace[1206882858] 'applied index is now lower than readState.Index' (duration: 26.11462ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:39.710418Z","caller":"traceutil/trace.go:171","msg":"trace[277077742] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"143.917133ms","start":"2026-10-14T03:17:39.566474Z","end":"2026-10-14T03:17:39.710391Z","steps":["trace[277077742] 'process raft request' (duration: 143.79281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.710835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.360414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-14T03:17:39.710862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.537105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2ea67b5-1489-42bf-a57b-2ee246d25b35\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-10-14T03:17:39.710954Z","caller":"traceutil/trace.go:171","msg":"trace[1596240163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26748; }","duration":"144.601081ms","start":"2026-10-14T03:17:39.566327Z","end":"2026-10-14T03:17:39.710928Z","steps":["trace[1596240163] 'agreement among raft nodes before linearized reading' (duration: 144.317314ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.710972Z","caller":"traceutil/trace.go:171","msg":"trace[1393976910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2ea67b5-1489-42bf-a57b-2ee246d25b35; range_end:; response_count:1; response_revision:26748; }","duration":"115.702049ms","start":"2026-10-14T03:17:39.595243Z","end":"2026-10-14T03:17:39.710945Z","steps":["trace[1393976910] 'agreement among raft nodes before linearized reading' (duration: 115.379151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.71089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.242953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:17:39.711052Z","caller":"traceutil/trace.go:171","msg":"trace[1375933529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26748; }","duration":"146.449478ms","start":"2026-10-14T03:17:39.564586Z","end":"2026-10-14T03:17:39.711036Z","steps":["trace[1375933529] 'agreement among raft nodes before linearized reading' (duration: 146.208592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.71121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.934862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T03:17:39.711276Z","caller":"traceutil/trace.go:171","msg":"trace[1764196134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26748; }","duration":"147.059135ms","start":"2026-10-14T03:17:39.564196Z","end":"2026-10-14T03:17:39.711255Z","steps":["trace[1764196134] 'agreement among raft nodes before linearized reading' (duration: 146.361726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.712043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.919846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:17:39.712129Z","caller":"traceutil/trace.go:171","msg":"trace[2024557374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26748; }","duration":"148.085761ms","start":"2026-10-14T03:17:39.564025Z","end":"2026-10-14T03:17:39.712111Z","steps":["trace[2024557374] 'agreement among raft nodes before linearized reading' (duration: 146.427498ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.897593Z","caller":"traceutil/trace.go:171","msg":"trace[1434055832] transaction","detail":"{read_only:false; response_revision:26749; number_of_response:1; }","duration":"182.76698ms","start":"2026-10-14T03:17:39.714787Z","end":"2026-10-14T03:17:39.897554Z","steps":["trace[1434055832] 'process raft request' (duration: 182.029712ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.924568Z","caller":"traceutil/trace.go:171","msg":"trace[1256925164] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"209.017775ms","start":"2026-10-14T03:17:39.715522Z","end":"2026-10-14T03:17:39.92454Z","steps":["trace[1256925164] 'process raft request' (duration: 208.80255ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.924836Z","caller":"traceutil/trace.go:171","msg":"trace[1301510629] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"207.578937ms","start":"2026-10-14T03:17:39.717245Z","end":"2026-10-14T03:17:39.924824Z","steps":["trace[1301510629] 'process raft request' (duration: 207.248589ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:39.924853Z","caller":"traceutil/trace.go:171","msg":"trace[1213543633] linearizableReadLoop","detail":"{readStateIndex:27638; appliedIndex:27634; }","duration":"136.31396ms","start":"2026-10-14T03:17:39.788519Z","end":"2026-10-14T03:17:39.924833Z","steps":["trace[1213543633] 'read index received' (duration: 108.253589ms)","trace[1213543633] 'applied index is now lower than readState.Index' (duration: 28.059371ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:39.924944Z","caller":"traceutil/trace.go:171","msg":"trace[1616470886] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"206.820599ms","start":"2026-10-14T03:17:39.718091Z","end":"2026-10-14T03:17:39.924912Z","steps":["trace[1616470886] 'process raft request' (duration: 206.634284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:39.925117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.576006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:39.925243Z","caller":"traceutil/trace.go:171","msg":"trace[1439875452] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26752; }","duration":"136.73458ms","start":"2026-10-14T03:17:39.788457Z","end":"2026-10-14T03:17:39.925192Z","steps":["trace[1439875452] 'agreement among raft nodes before linearized reading' (duration: 136.539015ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:40.314221Z","caller":"traceutil/trace.go:171","msg":"trace[1223814469] linearizableReadLoop","detail":"{readStateIndex:27639; appliedIndex:27638; }","duration":"159.72517ms","start":"2026-10-14T03:17:40.154431Z","end":"2026-10-14T03:17:40.314156Z","steps":["trace[1223814469] 'read index received' (duration: 159.430763ms)","trace[1223814469] 'applied index is now lower than readState.Index' (duration: 293.287µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:40.314285Z","caller":"traceutil/trace.go:171","msg":"trace[215690451] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"162.797658ms","start":"2026-10-14T03:17:40.151439Z","end":"2026-10-14T03:17:40.314236Z","steps":["trace[215690451] 'process raft request' (duration: 162.407408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:40.31462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.088739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:40.314815Z","caller":"traceutil/trace.go:171","msg":"trace[732683145] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26753; }","duration":"160.332805ms","start":"2026-10-14T03:17:40.154404Z","end":"2026-10-14T03:17:40.314737Z","steps":["trace[732683145] 'agreement among raft nodes before linearized reading' (duration: 159.941665ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:40.62889Z","caller":"traceutil/trace.go:171","msg":"trace[1051558408] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"406.075963ms","start":"2026-10-14T03:17:40.22274Z","end":"2026-10-14T03:17:40.628816Z","steps":["trace[1051558408] 'process raft request' (duration: 402.796849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:40.628939Z","caller":"traceutil/trace.go:171","msg":"trace[1320163318] linearizableReadLoop","detail":"{readStateIndex:27641; appliedIndex:27639; }","duration":"309.047472ms","start":"2026-10-14T03:17:40.319859Z","end":"2026-10-14T03:17:40.628907Z","steps":["trace[1320163318] 'read index received' (duration: 305.689296ms)","trace[1320163318] 'applied index is now lower than readState.Index' (duration: 3.356446ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:40.629044Z","caller":"traceutil/trace.go:171","msg":"trace[1539260030] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"406.123274ms","start":"2026-10-14T03:17:40.222901Z","end":"2026-10-14T03:17:40.629024Z","steps":["trace[1539260030] 'process raft request' (duration: 405.855727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:40.629138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:40.22268Z","time spent":"406.326668ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:40.629206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:40.222887Z","time spent":"406.214766ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:40.629217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.360489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T03:17:40.629254Z","caller":"traceutil/trace.go:171","msg":"trace[612218825] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26755; }","duration":"309.446221ms","start":"2026-10-14T03:17:40.319796Z","end":"2026-10-14T03:17:40.629242Z","steps":["trace[612218825] 'agreement among raft nodes before linearized reading' (duration: 309.272927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:40.629282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:40.319766Z","time spent":"309.509643ms","remote":"127.0.0.1:37354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T03:17:40.629435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.654144ms","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-10-14T03:17:40.629519Z","caller":"traceutil/trace.go:171","msg":"trace[1199074060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26755; }","duration":"155.827629ms","start":"2026-10-14T03:17:40.47367Z","end":"2026-10-14T03:17:40.629498Z","steps":["trace[1199074060] 'agreement among raft nodes before linearized reading' (duration: 155.560992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:47.851768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.278257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:47.851879Z","caller":"traceutil/trace.go:171","msg":"trace[362620637] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26825; }","duration":"143.490383ms","start":"2026-10-14T03:17:47.70836Z","end":"2026-10-14T03:17:47.85185Z","steps":["trace[362620637] 'count revisions from in-memory index tree' (duration: 143.167195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:47.852257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.148323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:17:47.852569Z","caller":"traceutil/trace.go:171","msg":"trace[1805364236] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"138.207668ms","start":"2026-10-14T03:17:47.714324Z","end":"2026-10-14T03:17:47.852532Z","steps":["trace[1805364236] 'process raft request' (duration: 26.656055ms)","trace[1805364236] 'compare' (duration: 110.891295ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:47.89751Z","caller":"traceutil/trace.go:171","msg":"trace[1207496327] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"159.634377ms","start":"2026-10-14T03:17:47.737845Z","end":"2026-10-14T03:17:47.897479Z","steps":["trace[1207496327] 'process raft request' (duration: 159.435252ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.142736Z","caller":"traceutil/trace.go:171","msg":"trace[2134895649] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"397.248125ms","start":"2026-10-14T03:17:47.745472Z","end":"2026-10-14T03:17:48.142721Z","steps":["trace[2134895649] 'process raft request' (duration: 397.009129ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.143007Z","caller":"traceutil/trace.go:171","msg":"trace[1073174895] transaction","detail":"{read_only:false; response_revision:26830; number_of_response:1; }","duration":"395.889911ms","start":"2026-10-14T03:17:47.747073Z","end":"2026-10-14T03:17:48.142963Z","steps":["trace[1073174895] 'process raft request' (duration: 395.580953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.143098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:47.745435Z","time spent":"397.377379ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:48.143202Z","caller":"traceutil/trace.go:171","msg":"trace[1685231469] transaction","detail":"{read_only:false; response_revision:26831; number_of_response:1; }","duration":"395.545852ms","start":"2026-10-14T03:17:47.747629Z","end":"2026-10-14T03:17:48.143175Z","steps":["trace[1685231469] 'process raft request' (duration: 395.250524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.143259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:47.747058Z","time spent":"396.071025ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:48.143416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:47.747576Z","time spent":"395.717497ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:48.142588Z","caller":"traceutil/trace.go:171","msg":"trace[440834170] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"399.943305ms","start":"2026-10-14T03:17:47.742615Z","end":"2026-10-14T03:17:48.142559Z","steps":["trace[440834170] 'process raft request' (duration: 399.682449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.143628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:47.742587Z","time spent":"400.89071ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:17:48.147622Z","caller":"traceutil/trace.go:171","msg":"trace[799586312] linearizableReadLoop","detail":"{readStateIndex:27719; appliedIndex:27714; }","duration":"208.935133ms","start":"2026-10-14T03:17:47.938667Z","end":"2026-10-14T03:17:48.147602Z","steps":["trace[799586312] 'read index received' (duration: 203.589486ms)","trace[799586312] 'applied index is now lower than readState.Index' (duration: 5.343087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:48.147826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.160239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T03:17:48.147881Z","caller":"traceutil/trace.go:171","msg":"trace[957802945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26831; }","duration":"209.263932ms","start":"2026-10-14T03:17:47.938603Z","end":"2026-10-14T03:17:48.147867Z","steps":["trace[957802945] 'agreement among raft nodes before linearized reading' (duration: 209.076127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.148103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.377832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:17:48.148188Z","caller":"traceutil/trace.go:171","msg":"trace[141091845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26832; }","duration":"191.518086ms","start":"2026-10-14T03:17:47.956649Z","end":"2026-10-14T03:17:48.148167Z","steps":["trace[141091845] 'agreement among raft nodes before linearized reading' (duration: 191.333671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.148311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.061085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-14T03:17:48.148343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.610037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:17:48.148366Z","caller":"traceutil/trace.go:171","msg":"trace[595913180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26832; }","duration":"170.178737ms","start":"2026-10-14T03:17:47.978172Z","end":"2026-10-14T03:17:48.14835Z","steps":["trace[595913180] 'agreement among raft nodes before linearized reading' (duration: 170.059994ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.148406Z","caller":"traceutil/trace.go:171","msg":"trace[1479496742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26832; }","duration":"173.700398ms","start":"2026-10-14T03:17:47.974692Z","end":"2026-10-14T03:17:48.148392Z","steps":["trace[1479496742] 'agreement among raft nodes before linearized reading' (duration: 173.497863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.148512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.159197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:17:48.14859Z","caller":"traceutil/trace.go:171","msg":"trace[1091612368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26832; }","duration":"177.26999ms","start":"2026-10-14T03:17:47.971303Z","end":"2026-10-14T03:17:48.148573Z","steps":["trace[1091612368] 'agreement among raft nodes before linearized reading' (duration: 177.013533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.148861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.176934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-14T03:17:48.149534Z","caller":"traceutil/trace.go:171","msg":"trace[1649315457] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26832; }","duration":"127.882702ms","start":"2026-10-14T03:17:48.021629Z","end":"2026-10-14T03:17:48.149512Z","steps":["trace[1649315457] 'agreement among raft nodes before linearized reading' (duration: 126.386024ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:59.619816Z","caller":"traceutil/trace.go:171","msg":"trace[1346289799] linearizableReadLoop","detail":"{readStateIndex:27811; appliedIndex:27810; }","duration":"326.12414ms","start":"2026-10-14T03:17:59.293659Z","end":"2026-10-14T03:17:59.619783Z","steps":["trace[1346289799] 'read index received' (duration: 325.881784ms)","trace[1346289799] 'applied index is now lower than readState.Index' (duration: 240.826µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:59.61997Z","caller":"traceutil/trace.go:171","msg":"trace[2053148945] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"389.773663ms","start":"2026-10-14T03:17:59.230183Z","end":"2026-10-14T03:17:59.619957Z","steps":["trace[2053148945] 'process raft request' (duration: 389.373553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:59.620102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:59.230153Z","time spent":"389.850745ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:17:59.620515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.828078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-14T03:17:59.620609Z","caller":"traceutil/trace.go:171","msg":"trace[1480220100] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26921; }","duration":"326.959142ms","start":"2026-10-14T03:17:59.293626Z","end":"2026-10-14T03:17:59.620585Z","steps":["trace[1480220100] 'agreement among raft nodes before linearized reading' (duration: 326.781967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:59.620648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:59.293581Z","time spent":"327.058335ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-10-14T03:18:00.01078Z","caller":"traceutil/trace.go:171","msg":"trace[106247378] transaction","detail":"{read_only:false; response_revision:26922; number_of_response:1; }","duration":"334.690661ms","start":"2026-10-14T03:17:59.675901Z","end":"2026-10-14T03:18:00.010592Z","steps":["trace[106247378] 'process raft request' (duration: 306.849286ms)","trace[106247378] 'compare' (duration: 27.325382ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:18:00.01106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:17:59.675866Z","time spent":"335.03305ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:18:00.019249Z","caller":"traceutil/trace.go:171","msg":"trace[15124017] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"107.191861ms","start":"2026-10-14T03:17:59.91204Z","end":"2026-10-14T03:18:00.019231Z","steps":["trace[15124017] 'process raft request' (duration: 107.033957ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.342985Z","caller":"traceutil/trace.go:171","msg":"trace[134449378] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"136.694088ms","start":"2026-10-14T03:18:00.206259Z","end":"2026-10-14T03:18:00.342953Z","steps":["trace[134449378] 'process raft request' (duration: 136.479412ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.546326Z","caller":"traceutil/trace.go:171","msg":"trace[1691140643] linearizableReadLoop","detail":"{readStateIndex:27819; appliedIndex:27815; }","duration":"321.387919ms","start":"2026-10-14T03:18:00.224904Z","end":"2026-10-14T03:18:00.546292Z","steps":["trace[1691140643] 'read index received' (duration: 117.860475ms)","trace[1691140643] 'applied index is now lower than readState.Index' (duration: 203.525904ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:00.546327Z","caller":"traceutil/trace.go:171","msg":"trace[194393420] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"339.034052ms","start":"2026-10-14T03:18:00.207237Z","end":"2026-10-14T03:18:00.546271Z","steps":["trace[194393420] 'process raft request' (duration: 322.038796ms)","trace[194393420] 'compare' (duration: 16.38019ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:00.546447Z","caller":"traceutil/trace.go:171","msg":"trace[1381810363] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"329.535578ms","start":"2026-10-14T03:18:00.21689Z","end":"2026-10-14T03:18:00.546426Z","steps":["trace[1381810363] 'process raft request' (duration: 329.279012ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.54648Z","caller":"traceutil/trace.go:171","msg":"trace[1226518620] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"328.556752ms","start":"2026-10-14T03:18:00.2179Z","end":"2026-10-14T03:18:00.546456Z","steps":["trace[1226518620] 'process raft request' (duration: 328.332577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.546562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.207224Z","time spent":"339.219597ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:18:00.546587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.216877Z","time spent":"329.61578ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:18:00.546591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.217884Z","time spent":"328.646575ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:18:00.546617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.699177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-14T03:18:00.54666Z","caller":"traceutil/trace.go:171","msg":"trace[2034351607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26928; }","duration":"321.772969ms","start":"2026-10-14T03:18:00.224873Z","end":"2026-10-14T03:18:00.546646Z","steps":["trace[2034351607] 'agreement among raft nodes before linearized reading' (duration: 321.600255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.546713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.224861Z","time spent":"321.825ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T03:18:00.546789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.359509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-14T03:18:00.546813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.867202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T03:18:00.54686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.356912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:18:00.54687Z","caller":"traceutil/trace.go:171","msg":"trace[657533002] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:26928; }","duration":"254.984824ms","start":"2026-10-14T03:18:00.291868Z","end":"2026-10-14T03:18:00.546853Z","steps":["trace[657533002] 'agreement among raft nodes before linearized reading' (duration: 254.875191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.546892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.570135ms","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-10-14T03:18:00.546905Z","caller":"traceutil/trace.go:171","msg":"trace[1431831381] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26928; }","duration":"110.457144ms","start":"2026-10-14T03:18:00.436433Z","end":"2026-10-14T03:18:00.54689Z","steps":["trace[1431831381] 'agreement among raft nodes before linearized reading' (duration: 110.387782ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.546925Z","caller":"traceutil/trace.go:171","msg":"trace[1059790125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26928; }","duration":"295.623587ms","start":"2026-10-14T03:18:00.251294Z","end":"2026-10-14T03:18:00.546918Z","steps":["trace[1059790125] 'agreement among raft nodes before linearized reading' (duration: 295.548245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.546826Z","caller":"traceutil/trace.go:171","msg":"trace[1679563981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26928; }","duration":"316.443832ms","start":"2026-10-14T03:18:00.230373Z","end":"2026-10-14T03:18:00.546817Z","steps":["trace[1679563981] 'agreement among raft nodes before linearized reading' (duration: 316.3487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.547079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.230351Z","time spent":"316.712839ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T03:18:00.547233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.112499ms","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-10-14T03:18:00.547283Z","caller":"traceutil/trace.go:171","msg":"trace[2039085403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26928; }","duration":"310.209631ms","start":"2026-10-14T03:18:00.237059Z","end":"2026-10-14T03:18:00.547269Z","steps":["trace[2039085403] 'agreement among raft nodes before linearized reading' (duration: 310.084638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.547318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:18:00.237038Z","time spent":"310.271843ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T03:18:00.723107Z","caller":"traceutil/trace.go:171","msg":"trace[1888217722] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"170.075096ms","start":"2026-10-14T03:18:00.552987Z","end":"2026-10-14T03:18:00.723062Z","steps":["trace[1888217722] 'process raft request' (duration: 142.132629ms)","trace[1888217722] 'compare' (duration: 27.698121ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:00.723283Z","caller":"traceutil/trace.go:171","msg":"trace[1861783295] transaction","detail":"{read_only:false; response_revision:26931; number_of_response:1; }","duration":"169.997763ms","start":"2026-10-14T03:18:00.55327Z","end":"2026-10-14T03:18:00.723268Z","steps":["trace[1861783295] 'process raft request' (duration: 169.767627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.723136Z","caller":"traceutil/trace.go:171","msg":"trace[451535090] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"170.008394ms","start":"2026-10-14T03:18:00.553084Z","end":"2026-10-14T03:18:00.723093Z","steps":["trace[451535090] 'process raft request' (duration: 169.903281ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.723568Z","caller":"traceutil/trace.go:171","msg":"trace[1450299259] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"170.045803ms","start":"2026-10-14T03:18:00.55351Z","end":"2026-10-14T03:18:00.723556Z","steps":["trace[1450299259] 'process raft request' (duration: 169.710465ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.840459Z","caller":"traceutil/trace.go:171","msg":"trace[1075743331] transaction","detail":"{read_only:false; response_revision:26933; number_of_response:1; }","duration":"127.513353ms","start":"2026-10-14T03:18:00.712913Z","end":"2026-10-14T03:18:00.840426Z","steps":["trace[1075743331] 'process raft request' (duration: 126.901148ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.840932Z","caller":"traceutil/trace.go:171","msg":"trace[1217402067] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"127.03464ms","start":"2026-10-14T03:18:00.713885Z","end":"2026-10-14T03:18:00.840919Z","steps":["trace[1217402067] 'process raft request' (duration: 126.63138ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:00.840965Z","caller":"traceutil/trace.go:171","msg":"trace[1783334869] linearizableReadLoop","detail":"{readStateIndex:27825; appliedIndex:27823; }","duration":"115.72139ms","start":"2026-10-14T03:18:00.725199Z","end":"2026-10-14T03:18:00.840921Z","steps":["trace[1783334869] 'read index received' (duration: 114.631972ms)","trace[1783334869] 'applied index is now lower than readState.Index' (duration: 1.087028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:18:00.841364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:124641"} {"level":"info","ts":"2026-10-14T03:18:00.841498Z","caller":"traceutil/trace.go:171","msg":"trace[735432094] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:26934; }","duration":"116.297175ms","start":"2026-10-14T03:18:00.725171Z","end":"2026-10-14T03:18:00.841468Z","steps":["trace[735432094] 'agreement among raft nodes before linearized reading' (duration: 115.877794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:00.841862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.617967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:92 size:121488"} {"level":"info","ts":"2026-10-14T03:18:00.841914Z","caller":"traceutil/trace.go:171","msg":"trace[1547777298] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:92; response_revision:26934; }","duration":"115.7182ms","start":"2026-10-14T03:18:00.726183Z","end":"2026-10-14T03:18:00.841901Z","steps":["trace[1547777298] 'agreement among raft nodes before linearized reading' (duration: 114.95292ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.896048Z","caller":"traceutil/trace.go:171","msg":"trace[115613916] transaction","detail":"{read_only:false; response_revision:26964; number_of_response:1; }","duration":"150.113403ms","start":"2026-10-14T03:18:04.745892Z","end":"2026-10-14T03:18:04.896006Z","steps":["trace[115613916] 'process raft request' (duration: 73.035334ms)","trace[115613916] 'compare' (duration: 76.839893ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:04.896365Z","caller":"traceutil/trace.go:171","msg":"trace[1713725188] transaction","detail":"{read_only:false; response_revision:26965; number_of_response:1; }","duration":"149.929148ms","start":"2026-10-14T03:18:04.746398Z","end":"2026-10-14T03:18:04.896328Z","steps":["trace[1713725188] 'process raft request' (duration: 149.549179ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.896464Z","caller":"traceutil/trace.go:171","msg":"trace[104738487] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"148.873961ms","start":"2026-10-14T03:18:04.747559Z","end":"2026-10-14T03:18:04.896433Z","steps":["trace[104738487] 'process raft request' (duration: 148.649115ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.896618Z","caller":"traceutil/trace.go:171","msg":"trace[2080688271] linearizableReadLoop","detail":"{readStateIndex:27859; appliedIndex:27854; }","duration":"141.39671ms","start":"2026-10-14T03:18:04.755192Z","end":"2026-10-14T03:18:04.896589Z","steps":["trace[2080688271] 'read index received' (duration: 16.658568ms)","trace[2080688271] 'applied index is now lower than readState.Index' (duration: 124.736642ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:04.896661Z","caller":"traceutil/trace.go:171","msg":"trace[609921381] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"147.370213ms","start":"2026-10-14T03:18:04.749246Z","end":"2026-10-14T03:18:04.896616Z","steps":["trace[609921381] 'process raft request' (duration: 147.120256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:04.896893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.678216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-14T03:18:04.896955Z","caller":"traceutil/trace.go:171","msg":"trace[165927868] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26967; }","duration":"141.80594ms","start":"2026-10-14T03:18:04.755131Z","end":"2026-10-14T03:18:04.896937Z","steps":["trace[165927868] 'agreement among raft nodes before linearized reading' (duration: 141.585814ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.90974Z","caller":"traceutil/trace.go:171","msg":"trace[1948923640] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"127.203715ms","start":"2026-10-14T03:18:04.782513Z","end":"2026-10-14T03:18:04.909717Z","steps":["trace[1948923640] 'process raft request' (duration: 126.931827ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.909827Z","caller":"traceutil/trace.go:171","msg":"trace[475842065] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"126.61827ms","start":"2026-10-14T03:18:04.783187Z","end":"2026-10-14T03:18:04.909805Z","steps":["trace[475842065] 'process raft request' (duration: 126.429216ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.91011Z","caller":"traceutil/trace.go:171","msg":"trace[1728533435] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"115.891805ms","start":"2026-10-14T03:18:04.794175Z","end":"2026-10-14T03:18:04.910067Z","steps":["trace[1728533435] 'process raft request' (duration: 115.747531ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.910173Z","caller":"traceutil/trace.go:171","msg":"trace[1366742459] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"126.61696ms","start":"2026-10-14T03:18:04.783518Z","end":"2026-10-14T03:18:04.910135Z","steps":["trace[1366742459] 'process raft request' (duration: 126.193729ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.977653Z","caller":"traceutil/trace.go:171","msg":"trace[2051113491] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"120.468602ms","start":"2026-10-14T03:18:04.857146Z","end":"2026-10-14T03:18:04.977614Z","steps":["trace[2051113491] 'process raft request' (duration: 120.224586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:04.978066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.632804ms","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-10-14T03:18:04.978048Z","caller":"traceutil/trace.go:171","msg":"trace[1254780618] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"120.742108ms","start":"2026-10-14T03:18:04.857257Z","end":"2026-10-14T03:18:04.977999Z","steps":["trace[1254780618] 'process raft request' (duration: 120.307537ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:04.978117Z","caller":"traceutil/trace.go:171","msg":"trace[1575145325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26973; }","duration":"109.758467ms","start":"2026-10-14T03:18:04.868344Z","end":"2026-10-14T03:18:04.978102Z","steps":["trace[1575145325] 'agreement among raft nodes before linearized reading' (duration: 109.578792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:04.978041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.493498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:18:04.978457Z","caller":"traceutil/trace.go:171","msg":"trace[88613382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26973; }","duration":"195.96916ms","start":"2026-10-14T03:18:04.782458Z","end":"2026-10-14T03:18:04.978428Z","steps":["trace[88613382] 'agreement among raft nodes before linearized reading' (duration: 195.468228ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:05.093905Z","caller":"traceutil/trace.go:171","msg":"trace[1423791219] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"109.184863ms","start":"2026-10-14T03:18:04.984368Z","end":"2026-10-14T03:18:05.093553Z","steps":["trace[1423791219] 'process raft request' (duration: 63.968622ms)","trace[1423791219] 'compare' (duration: 44.481472ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:05.15597Z","caller":"traceutil/trace.go:171","msg":"trace[95710639] transaction","detail":"{read_only:false; response_revision:26975; number_of_response:1; }","duration":"163.873226ms","start":"2026-10-14T03:18:04.992069Z","end":"2026-10-14T03:18:05.155942Z","steps":["trace[95710639] 'process raft request' (duration: 163.708712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:06.206446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.130083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:18:06.206588Z","caller":"traceutil/trace.go:171","msg":"trace[1270922831] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"154.354482ms","start":"2026-10-14T03:18:06.052201Z","end":"2026-10-14T03:18:06.206555Z","steps":["trace[1270922831] 'process raft request' (duration: 26.976333ms)","trace[1270922831] 'compare' (duration: 126.941188ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:16.769798Z","caller":"traceutil/trace.go:171","msg":"trace[1317923318] linearizableReadLoop","detail":"{readStateIndex:27950; appliedIndex:27949; }","duration":"107.450728ms","start":"2026-10-14T03:18:16.662316Z","end":"2026-10-14T03:18:16.769767Z","steps":["trace[1317923318] 'read index received' (duration: 107.244793ms)","trace[1317923318] 'applied index is now lower than readState.Index' (duration: 204.015µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:16.769875Z","caller":"traceutil/trace.go:171","msg":"trace[461748677] transaction","detail":"{read_only:false; response_revision:27056; number_of_response:1; }","duration":"151.725864ms","start":"2026-10-14T03:18:16.618101Z","end":"2026-10-14T03:18:16.769827Z","steps":["trace[461748677] 'process raft request' (duration: 151.446937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:16.770068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.721485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19150"} {"level":"info","ts":"2026-10-14T03:18:16.770113Z","caller":"traceutil/trace.go:171","msg":"trace[1888920635] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27056; }","duration":"107.845797ms","start":"2026-10-14T03:18:16.662254Z","end":"2026-10-14T03:18:16.7701Z","steps":["trace[1888920635] 'agreement among raft nodes before linearized reading' (duration: 107.632832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:16.788154Z","caller":"traceutil/trace.go:171","msg":"trace[642743734] transaction","detail":"{read_only:false; response_revision:27057; number_of_response:1; }","duration":"101.406572ms","start":"2026-10-14T03:18:16.686709Z","end":"2026-10-14T03:18:16.788116Z","steps":["trace[642743734] 'process raft request' (duration: 101.193887ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:18.924499Z","caller":"traceutil/trace.go:171","msg":"trace[95341642] transaction","detail":"{read_only:false; response_revision:27072; number_of_response:1; }","duration":"148.091661ms","start":"2026-10-14T03:18:18.776181Z","end":"2026-10-14T03:18:18.924273Z","steps":["trace[95341642] 'process raft request' (duration: 147.907677ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:19.094559Z","caller":"traceutil/trace.go:171","msg":"trace[190175079] linearizableReadLoop","detail":"{readStateIndex:27968; appliedIndex:27967; }","duration":"139.793538ms","start":"2026-10-14T03:18:18.954736Z","end":"2026-10-14T03:18:19.094529Z","steps":["trace[190175079] 'read index received' (duration: 46.370551ms)","trace[190175079] 'applied index is now lower than readState.Index' (duration: 93.421837ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:19.094675Z","caller":"traceutil/trace.go:171","msg":"trace[141028714] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"299.827926ms","start":"2026-10-14T03:18:18.794827Z","end":"2026-10-14T03:18:19.094654Z","steps":["trace[141028714] 'process raft request' (duration: 206.370467ms)","trace[141028714] 'compare' (duration: 93.028188ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:18:19.094766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.004843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:18:19.094809Z","caller":"traceutil/trace.go:171","msg":"trace[1631284927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27073; }","duration":"140.138777ms","start":"2026-10-14T03:18:18.954659Z","end":"2026-10-14T03:18:19.094798Z","steps":["trace[1631284927] 'agreement among raft nodes before linearized reading' (duration: 139.996954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:19.172958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.079351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-14T03:18:19.173092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.251956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:18:19.173155Z","caller":"traceutil/trace.go:171","msg":"trace[1521876324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27075; }","duration":"180.348249ms","start":"2026-10-14T03:18:18.992786Z","end":"2026-10-14T03:18:19.173135Z","steps":["trace[1521876324] 'agreement among raft nodes before linearized reading' (duration: 180.234326ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:19.173112Z","caller":"traceutil/trace.go:171","msg":"trace[364519041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27075; }","duration":"180.274087ms","start":"2026-10-14T03:18:18.992819Z","end":"2026-10-14T03:18:19.173093Z","steps":["trace[364519041] 'agreement among raft nodes before linearized reading' (duration: 179.963529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:19.173453Z","caller":"traceutil/trace.go:171","msg":"trace[1474788432] transaction","detail":"{read_only:false; response_revision:27075; number_of_response:1; }","duration":"135.900128ms","start":"2026-10-14T03:18:19.037542Z","end":"2026-10-14T03:18:19.173442Z","steps":["trace[1474788432] 'process raft request' (duration: 134.918353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:19.172985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.499936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:18:19.173583Z","caller":"traceutil/trace.go:171","msg":"trace[1144788938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27075; }","duration":"185.096711ms","start":"2026-10-14T03:18:18.988463Z","end":"2026-10-14T03:18:19.17356Z","steps":["trace[1144788938] 'agreement among raft nodes before linearized reading' (duration: 184.452574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:18:19.172964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.150404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T03:18:19.173683Z","caller":"traceutil/trace.go:171","msg":"trace[1355742324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27075; }","duration":"180.902472ms","start":"2026-10-14T03:18:18.992767Z","end":"2026-10-14T03:18:19.17367Z","steps":["trace[1355742324] 'agreement among raft nodes before linearized reading' (duration: 179.992019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:20.83978Z","caller":"traceutil/trace.go:171","msg":"trace[1168385693] transaction","detail":"{read_only:false; response_revision:27084; number_of_response:1; }","duration":"100.51944ms","start":"2026-10-14T03:18:20.739233Z","end":"2026-10-14T03:18:20.839753Z","steps":["trace[1168385693] 'process raft request' (duration: 100.308845ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:24.541231Z","caller":"traceutil/trace.go:171","msg":"trace[1811092232] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"144.665412ms","start":"2026-10-14T03:18:24.396523Z","end":"2026-10-14T03:18:24.541189Z","steps":["trace[1811092232] 'process raft request' (duration: 144.398315ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:24.652201Z","caller":"traceutil/trace.go:171","msg":"trace[1491477380] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"255.209981ms","start":"2026-10-14T03:18:24.39694Z","end":"2026-10-14T03:18:24.65215Z","steps":["trace[1491477380] 'process raft request' (duration: 189.104203ms)","trace[1491477380] 'compare' (duration: 65.768369ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:24.652277Z","caller":"traceutil/trace.go:171","msg":"trace[1254125171] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"185.980583ms","start":"2026-10-14T03:18:24.466257Z","end":"2026-10-14T03:18:24.652237Z","steps":["trace[1254125171] 'process raft request' (duration: 185.898691ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:34.824985Z","caller":"traceutil/trace.go:171","msg":"trace[616780527] transaction","detail":"{read_only:false; response_revision:27186; number_of_response:1; }","duration":"134.759168ms","start":"2026-10-14T03:18:34.690184Z","end":"2026-10-14T03:18:34.824943Z","steps":["trace[616780527] 'process raft request' (duration: 57.424944ms)","trace[616780527] 'compare' (duration: 77.17142ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:18:34.850891Z","caller":"traceutil/trace.go:171","msg":"trace[1583590753] transaction","detail":"{read_only:false; response_revision:27187; number_of_response:1; }","duration":"160.611523ms","start":"2026-10-14T03:18:34.690249Z","end":"2026-10-14T03:18:34.850861Z","steps":["trace[1583590753] 'process raft request' (duration: 160.406557ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:18:59.067975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24585} {"level":"info","ts":"2026-10-14T03:18:59.238358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24585,"took":"164.536383ms","hash":234076001,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":37007360,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-14T03:18:59.238433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":234076001,"revision":24585,"compact-revision":21617} {"level":"info","ts":"2026-10-14T03:19:01.494019Z","caller":"traceutil/trace.go:171","msg":"trace[1662529627] transaction","detail":"{read_only:false; response_revision:27535; number_of_response:1; }","duration":"225.871507ms","start":"2026-10-14T03:19:01.268123Z","end":"2026-10-14T03:19:01.493994Z","steps":["trace[1662529627] 'process raft request' (duration: 198.185836ms)","trace[1662529627] 'compare' (duration: 27.141807ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:19:01.569696Z","caller":"traceutil/trace.go:171","msg":"trace[2111324191] linearizableReadLoop","detail":"{readStateIndex:28443; appliedIndex:28440; }","duration":"170.328952ms","start":"2026-10-14T03:19:01.399321Z","end":"2026-10-14T03:19:01.56965Z","steps":["trace[2111324191] 'read index received' (duration: 67.00546ms)","trace[2111324191] 'applied index is now lower than readState.Index' (duration: 103.322212ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:19:01.569752Z","caller":"traceutil/trace.go:171","msg":"trace[346288522] transaction","detail":"{read_only:false; response_revision:27536; number_of_response:1; }","duration":"300.591414ms","start":"2026-10-14T03:19:01.269138Z","end":"2026-10-14T03:19:01.56973Z","steps":["trace[346288522] 'process raft request' (duration: 300.308467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:01.569889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.269124Z","time spent":"300.691017ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:19:01.570133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.767173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-b58g9.18de465a5feb7fb4\" ","response":"range_response_count:1 size:1419"} {"level":"info","ts":"2026-10-14T03:19:01.570255Z","caller":"traceutil/trace.go:171","msg":"trace[2099246877] transaction","detail":"{read_only:false; response_revision:27537; number_of_response:1; }","duration":"191.34241ms","start":"2026-10-14T03:19:01.378892Z","end":"2026-10-14T03:19:01.570235Z","steps":["trace[2099246877] 'process raft request' (duration: 190.709514ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:19:01.57025Z","caller":"traceutil/trace.go:171","msg":"trace[1875744433] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-b58g9.18de465a5feb7fb4; range_end:; response_count:1; response_revision:27537; }","duration":"170.941018ms","start":"2026-10-14T03:19:01.399275Z","end":"2026-10-14T03:19:01.570216Z","steps":["trace[1875744433] 'agreement among raft nodes before linearized reading' (duration: 170.546308ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:19:01.892155Z","caller":"traceutil/trace.go:171","msg":"trace[1290477305] linearizableReadLoop","detail":"{readStateIndex:28444; appliedIndex:28443; }","duration":"317.462688ms","start":"2026-10-14T03:19:01.574663Z","end":"2026-10-14T03:19:01.892126Z","steps":["trace[1290477305] 'read index received' (duration: 239.497378ms)","trace[1290477305] 'applied index is now lower than readState.Index' (duration: 77.96385ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:19:01.892441Z","caller":"traceutil/trace.go:171","msg":"trace[396272505] transaction","detail":"{read_only:false; response_revision:27538; number_of_response:1; }","duration":"319.692196ms","start":"2026-10-14T03:19:01.572731Z","end":"2026-10-14T03:19:01.892424Z","steps":["trace[396272505] 'process raft request' (duration: 241.476169ms)","trace[396272505] 'compare' (duration: 77.780355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:19:01.892572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.572699Z","time spent":"319.779928ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:19:01.892933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.264269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T03:19:01.892973Z","caller":"traceutil/trace.go:171","msg":"trace[1782017184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27538; }","duration":"318.32826ms","start":"2026-10-14T03:19:01.574636Z","end":"2026-10-14T03:19:01.892964Z","steps":["trace[1782017184] 'agreement among raft nodes before linearized reading' (duration: 318.179306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:01.893008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.574617Z","time spent":"318.384202ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T03:19:01.905014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.887447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T03:19:01.90512Z","caller":"traceutil/trace.go:171","msg":"trace[161362444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27540; }","duration":"330.02396ms","start":"2026-10-14T03:19:01.575072Z","end":"2026-10-14T03:19:01.905096Z","steps":["trace[161362444] 'agreement among raft nodes before linearized reading' (duration: 329.772014ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:19:01.905111Z","caller":"traceutil/trace.go:171","msg":"trace[1800888341] transaction","detail":"{read_only:false; response_revision:27539; number_of_response:1; }","duration":"327.478745ms","start":"2026-10-14T03:19:01.577581Z","end":"2026-10-14T03:19:01.90506Z","steps":["trace[1800888341] 'process raft request' (duration: 327.047354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:01.905252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.949523ms","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-10-14T03:19:01.905235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.818287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T03:19:01.905282Z","caller":"traceutil/trace.go:171","msg":"trace[1845133594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27540; }","duration":"329.014654ms","start":"2026-10-14T03:19:01.576258Z","end":"2026-10-14T03:19:01.905273Z","steps":["trace[1845133594] 'agreement among raft nodes before linearized reading' (duration: 328.930862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:01.905323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.576238Z","time spent":"329.080016ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T03:19:01.905331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.577527Z","time spent":"327.68004ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:19:01.905346Z","caller":"traceutil/trace.go:171","msg":"trace[1192826060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27540; }","duration":"324.954961ms","start":"2026-10-14T03:19:01.580361Z","end":"2026-10-14T03:19:01.905316Z","steps":["trace[1192826060] 'agreement among raft nodes before linearized reading' (duration: 324.613862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:01.905162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.57506Z","time spent":"330.093753ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T03:19:01.905396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.580351Z","time spent":"325.036593ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T03:19:02.204351Z","caller":"traceutil/trace.go:171","msg":"trace[52929564] transaction","detail":"{read_only:false; response_revision:27541; number_of_response:1; }","duration":"305.969233ms","start":"2026-10-14T03:19:01.898343Z","end":"2026-10-14T03:19:02.204312Z","steps":["trace[52929564] 'process raft request' (duration: 265.440272ms)","trace[52929564] 'compare' (duration: 39.881985ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:19:02.20454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.898311Z","time spent":"306.119707ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:19:02.5967Z","caller":"traceutil/trace.go:171","msg":"trace[261223307] linearizableReadLoop","detail":"{readStateIndex:28450; appliedIndex:28446; }","duration":"681.917423ms","start":"2026-10-14T03:19:01.914742Z","end":"2026-10-14T03:19:02.59666Z","steps":["trace[261223307] 'read index received' (duration: 249.059563ms)","trace[261223307] 'applied index is now lower than readState.Index' (duration: 432.8565ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:19:02.596833Z","caller":"traceutil/trace.go:171","msg":"trace[349166758] transaction","detail":"{read_only:false; response_revision:27543; number_of_response:1; }","duration":"685.70116ms","start":"2026-10-14T03:19:01.911116Z","end":"2026-10-14T03:19:02.596817Z","steps":["trace[349166758] 'process raft request' (duration: 685.455914ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:19:02.596807Z","caller":"traceutil/trace.go:171","msg":"trace[1226867047] transaction","detail":"{read_only:false; response_revision:27542; number_of_response:1; }","duration":"685.68724ms","start":"2026-10-14T03:19:01.911074Z","end":"2026-10-14T03:19:02.596761Z","steps":["trace[1226867047] 'process raft request' (duration: 685.335051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.596935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.911085Z","time spent":"685.775151ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:19:02.59704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.911044Z","time spent":"685.873323ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:19:02.597288Z","caller":"traceutil/trace.go:171","msg":"trace[2064744986] transaction","detail":"{read_only:false; response_revision:27544; number_of_response:1; }","duration":"684.682573ms","start":"2026-10-14T03:19:01.912595Z","end":"2026-10-14T03:19:02.597278Z","steps":["trace[2064744986] 'process raft request' (duration: 684.021206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.597356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.912582Z","time spent":"684.739945ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:19:02.597386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.616371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T03:19:02.597494Z","caller":"traceutil/trace.go:171","msg":"trace[1957656848] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:27544; }","duration":"682.806565ms","start":"2026-10-14T03:19:01.914661Z","end":"2026-10-14T03:19:02.597467Z","steps":["trace[1957656848] 'agreement among raft nodes before linearized reading' (duration: 682.448266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.597544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.914646Z","time spent":"682.886217ms","remote":"127.0.0.1:41740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":168,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:19:02.634894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.077755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:19:02.634982Z","caller":"traceutil/trace.go:171","msg":"trace[2056561341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27546; }","duration":"680.242079ms","start":"2026-10-14T03:19:01.954721Z","end":"2026-10-14T03:19:02.634963Z","steps":["trace[2056561341] 'agreement among raft nodes before linearized reading' (duration: 680.053174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.635025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:01.954674Z","time spent":"680.341542ms","remote":"127.0.0.1:37184","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-10-14T03:19:02.635373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.98918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:19:02.635489Z","caller":"traceutil/trace.go:171","msg":"trace[446579872] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27546; }","duration":"487.120002ms","start":"2026-10-14T03:19:02.148358Z","end":"2026-10-14T03:19:02.635478Z","steps":["trace[446579872] 'agreement among raft nodes before linearized reading' (duration: 486.986519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.635521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:02.148348Z","time spent":"487.164913ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-10-14T03:19:02.635728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.514313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:19:02.635755Z","caller":"traceutil/trace.go:171","msg":"trace[1673579717] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27546; }","duration":"487.586064ms","start":"2026-10-14T03:19:02.14816Z","end":"2026-10-14T03:19:02.635747Z","steps":["trace[1673579717] 'agreement among raft nodes before linearized reading' (duration: 487.541983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.635776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:02.148124Z","time spent":"487.646936ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-10-14T03:19:02.635874Z","caller":"traceutil/trace.go:171","msg":"trace[1229630585] transaction","detail":"{read_only:false; response_revision:27545; number_of_response:1; }","duration":"254.694718ms","start":"2026-10-14T03:19:02.381148Z","end":"2026-10-14T03:19:02.635842Z","steps":["trace[1229630585] 'process raft request' (duration: 253.427245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.635955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.628971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:19:02.635988Z","caller":"traceutil/trace.go:171","msg":"trace[56681959] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27546; }","duration":"488.716654ms","start":"2026-10-14T03:19:02.147263Z","end":"2026-10-14T03:19:02.63598Z","steps":["trace[56681959] 'agreement among raft nodes before linearized reading' (duration: 488.676413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.63601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:02.14724Z","time spent":"488.764445ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-10-14T03:19:02.635919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.853611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:19:02.636244Z","caller":"traceutil/trace.go:171","msg":"trace[1284180555] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27546; }","duration":"488.190649ms","start":"2026-10-14T03:19:02.148038Z","end":"2026-10-14T03:19:02.636229Z","steps":["trace[1284180555] 'agreement among raft nodes before linearized reading' (duration: 487.853311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:19:02.636302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:19:02.148029Z","time spent":"488.24151ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-10-14T03:21:52.290694Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:21:52.298374Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T03:21:52.29859Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T03:23:59.075682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27508} {"level":"info","ts":"2026-10-14T03:23:59.233412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27508,"took":"151.874788ms","hash":3552599956,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":35516416,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T03:23:59.233496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3552599956,"revision":27508,"compact-revision":24585} {"level":"info","ts":"2026-10-14T03:28:59.083484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30252} {"level":"info","ts":"2026-10-14T03:28:59.251475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30252,"took":"161.638852ms","hash":2550999418,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":30810112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T03:28:59.251559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2550999418,"revision":30252,"compact-revision":27508} {"level":"info","ts":"2026-10-14T03:33:59.090809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32501} {"level":"info","ts":"2026-10-14T03:33:59.271097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32501,"took":"174.525504ms","hash":1816039849,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:33:59.27117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1816039849,"revision":32501,"compact-revision":30252} {"level":"info","ts":"2026-10-14T03:38:59.098718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34726} {"level":"info","ts":"2026-10-14T03:38:59.238219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34726,"took":"133.473262ms","hash":3993103850,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:38:59.23831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993103850,"revision":34726,"compact-revision":32501} {"level":"info","ts":"2026-10-14T03:42:48.023434Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:42:48.032055Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T03:42:48.032198Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T03:43:59.105911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36958} {"level":"info","ts":"2026-10-14T03:43:59.247167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36958,"took":"137.125013ms","hash":3036982644,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T03:43:59.247278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3036982644,"revision":36958,"compact-revision":34726} {"level":"info","ts":"2026-10-14T03:48:59.114326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39299} {"level":"info","ts":"2026-10-14T03:48:59.268619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39299,"took":"150.586801ms","hash":2823514246,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T03:48:59.268746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2823514246,"revision":39299,"compact-revision":36958} {"level":"info","ts":"2026-10-14T03:53:59.122972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41532} {"level":"info","ts":"2026-10-14T03:53:59.251629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41532,"took":"123.985532ms","hash":1115641722,"current-db-size-bytes":60178432,"current-db-size":"60 MB","current-db-size-in-use-bytes":30052352,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:53:59.251719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1115641722,"revision":41532,"compact-revision":39299}