{"level":"warn","ts":"2026-09-22T02:02:38.951712Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T02:02:38.951808Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-22T02:02:38.951875Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T02:02:38.951884Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-22T02:02:38.951902Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T02:02:38.952544Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-09-22T02:02:38.952611Z","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.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-22T02:02:38.956771Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.968131ms"} {"level":"info","ts":"2026-09-22T02:02:38.965932Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-09-22T02:02:38.966178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T02:02:38.966234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-09-22T02:02:38.966246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T02:02:38.966281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-09-22T02:02:38.966374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-09-22T02:02:38.984498Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T02:02:38.986555Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T02:02:38.989619Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-22T02:02:38.994883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T02:02:38.995237Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T02:02:38.995504Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T02:02:38.995621Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T02:02:38.995638Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T02:02:38.997009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-09-22T02:02:38.997205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-22T02:02:39.002819Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T02:02:39.002914Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-22T02:02:39.00298Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-22T02:02:39.003227Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T02:02:39.003279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T02:02:39.766849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T02:02:39.76692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T02:02:39.766974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-09-22T02:02:39.766997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-09-22T02:02:39.767008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-22T02:02:39.767024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-09-22T02:02:39.767038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-22T02:02:39.768446Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T02:02:39.76844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T02:02:39.768501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T02:02:39.768471Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:02:39.768861Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T02:02:39.76896Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T02:02:39.769865Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:02:39.770065Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:02:39.770147Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:02:39.770981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T02:02:39.772381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"warn","ts":"2026-09-22T02:02:58.768598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.219648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T02:03:04.278933Z","caller":"traceutil/trace.go:171","msg":"trace[1205463815] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"141.171718ms","start":"2026-09-22T02:03:04.137716Z","end":"2026-09-22T02:03:04.278888Z","steps":["trace[1205463815] 'process raft request' (duration: 81.692175ms)","trace[1205463815] 'compare' (duration: 59.260409ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:03:30.089464Z","caller":"traceutil/trace.go:171","msg":"trace[536763127] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"104.910283ms","start":"2026-09-22T02:03:29.984494Z","end":"2026-09-22T02:03:30.089404Z","steps":["trace[536763127] 'process raft request' (duration: 104.550993ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:37.445722Z","caller":"traceutil/trace.go:171","msg":"trace[1497200594] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"195.174663ms","start":"2026-09-22T02:03:37.250517Z","end":"2026-09-22T02:03:37.445692Z","steps":["trace[1497200594] 'process raft request' (duration: 194.998149ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:37.948374Z","caller":"traceutil/trace.go:171","msg":"trace[994907235] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"446.843287ms","start":"2026-09-22T02:03:37.501499Z","end":"2026-09-22T02:03:37.948342Z","steps":["trace[994907235] 'process raft request' (duration: 437.781269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:03:37.949111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:03:37.501452Z","time spent":"447.029012ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:04:49.113719Z","caller":"traceutil/trace.go:171","msg":"trace[368719451] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"164.713933ms","start":"2026-09-22T02:04:48.948961Z","end":"2026-09-22T02:04:49.113675Z","steps":["trace[368719451] 'process raft request' (duration: 89.351595ms)","trace[368719451] 'compare' (duration: 75.191944ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:05:29.610458Z","caller":"traceutil/trace.go:171","msg":"trace[873978274] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"122.371854ms","start":"2026-09-22T02:05:29.488062Z","end":"2026-09-22T02:05:29.610434Z","steps":["trace[873978274] 'process raft request' (duration: 122.132029ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:05:29.62153Z","caller":"traceutil/trace.go:171","msg":"trace[2132640785] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"108.750599ms","start":"2026-09-22T02:05:29.512677Z","end":"2026-09-22T02:05:29.621427Z","steps":["trace[2132640785] 'process raft request' (duration: 108.563455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:05:29.852632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.803057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:05:29.852782Z","caller":"traceutil/trace.go:171","msg":"trace[1577784933] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"217.59359ms","start":"2026-09-22T02:05:29.635159Z","end":"2026-09-22T02:05:29.852753Z","steps":["trace[1577784933] 'process raft request' (duration: 93.504638ms)","trace[1577784933] 'compare' (duration: 123.54596ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:06.541875Z","caller":"traceutil/trace.go:171","msg":"trace[277441182] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"113.496991ms","start":"2026-09-22T02:06:06.42834Z","end":"2026-09-22T02:06:06.541837Z","steps":["trace[277441182] 'process raft request' (duration: 113.238026ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:12.71628Z","caller":"traceutil/trace.go:171","msg":"trace[1280578492] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"112.968081ms","start":"2026-09-22T02:06:12.603281Z","end":"2026-09-22T02:06:12.716249Z","steps":["trace[1280578492] 'read index received' (duration: 112.737066ms)","trace[1280578492] 'applied index is now lower than readState.Index' (duration: 229.935µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:12.716379Z","caller":"traceutil/trace.go:171","msg":"trace[1861042938] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"144.470924ms","start":"2026-09-22T02:06:12.571859Z","end":"2026-09-22T02:06:12.71633Z","steps":["trace[1861042938] 'process raft request' (duration: 144.224798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:12.716582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.231857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:06:12.716644Z","caller":"traceutil/trace.go:171","msg":"trace[2015747681] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2286; }","duration":"113.37074ms","start":"2026-09-22T02:06:12.603256Z","end":"2026-09-22T02:06:12.716626Z","steps":["trace[2015747681] 'agreement among raft nodes before linearized reading' (duration: 113.153295ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:10:16.109692Z","caller":"traceutil/trace.go:171","msg":"trace[1027221788] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"100.927283ms","start":"2026-09-22T02:10:16.008694Z","end":"2026-09-22T02:10:16.109621Z","steps":["trace[1027221788] 'process raft request' (duration: 85.167134ms)","trace[1027221788] 'compare' (duration: 14.418681ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:20.77334Z","caller":"traceutil/trace.go:171","msg":"trace[481107906] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5430; }","duration":"108.107502ms","start":"2026-09-22T02:12:20.665189Z","end":"2026-09-22T02:12:20.773297Z","steps":["trace[481107906] 'read index received' (duration: 61.668801ms)","trace[481107906] 'applied index is now lower than readState.Index' (duration: 46.43786ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:12:20.773598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.381718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-22T02:12:20.773623Z","caller":"traceutil/trace.go:171","msg":"trace[1465182156] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5227; }","duration":"108.461949ms","start":"2026-09-22T02:12:20.665155Z","end":"2026-09-22T02:12:20.773617Z","steps":["trace[1465182156] 'agreement among raft nodes before linearized reading' (duration: 108.304866ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:20.773589Z","caller":"traceutil/trace.go:171","msg":"trace[1881779921] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"114.026839ms","start":"2026-09-22T02:12:20.659492Z","end":"2026-09-22T02:12:20.773519Z","steps":["trace[1881779921] 'process raft request' (duration: 67.686861ms)","trace[1881779921] 'compare' (duration: 45.98448ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:20.807643Z","caller":"traceutil/trace.go:171","msg":"trace[1270803866] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"140.884084ms","start":"2026-09-22T02:12:20.666725Z","end":"2026-09-22T02:12:20.807609Z","steps":["trace[1270803866] 'process raft request' (duration: 140.081556ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:20.808097Z","caller":"traceutil/trace.go:171","msg":"trace[1366549839] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"137.887599ms","start":"2026-09-22T02:12:20.67019Z","end":"2026-09-22T02:12:20.808077Z","steps":["trace[1366549839] 'process raft request' (duration: 136.798114ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:24.88173Z","caller":"traceutil/trace.go:171","msg":"trace[1002313742] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"302.875576ms","start":"2026-09-22T02:12:24.578823Z","end":"2026-09-22T02:12:24.881698Z","steps":["trace[1002313742] 'process raft request' (duration: 302.694823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:24.881926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:24.5788Z","time spent":"303.02841ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:12:24.898752Z","caller":"traceutil/trace.go:171","msg":"trace[1455267180] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5578; }","duration":"273.919277ms","start":"2026-09-22T02:12:24.624801Z","end":"2026-09-22T02:12:24.89872Z","steps":["trace[1455267180] 'read index received' (duration: 256.782483ms)","trace[1455267180] 'applied index is now lower than readState.Index' (duration: 17.135594ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:24.898937Z","caller":"traceutil/trace.go:171","msg":"trace[287685654] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"305.397301ms","start":"2026-09-22T02:12:24.59349Z","end":"2026-09-22T02:12:24.898887Z","steps":["trace[287685654] 'process raft request' (duration: 305.066353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:24.899051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.235143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2npfn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-22T02:12:24.899091Z","caller":"traceutil/trace.go:171","msg":"trace[1348444319] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2npfn; range_end:; response_count:1; response_revision:5370; }","duration":"274.349646ms","start":"2026-09-22T02:12:24.624729Z","end":"2026-09-22T02:12:24.899079Z","steps":["trace[1348444319] 'agreement among raft nodes before linearized reading' (duration: 274.137972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:24.899164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:24.593426Z","time spent":"305.634146ms","remote":"127.0.0.1:48302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:12:24.89925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.48919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:12:24.899424Z","caller":"traceutil/trace.go:171","msg":"trace[932254722] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5370; }","duration":"131.747954ms","start":"2026-09-22T02:12:24.767652Z","end":"2026-09-22T02:12:24.8994Z","steps":["trace[932254722] 'agreement among raft nodes before linearized reading' (duration: 131.275284ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:39.797509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-09-22T02:12:39.924285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"125.311456ms","hash":3580021652,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":31424512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:12:39.924914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3580021652,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-09-22T02:12:52.51529Z","caller":"traceutil/trace.go:171","msg":"trace[1676942606] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"450.383199ms","start":"2026-09-22T02:12:52.064856Z","end":"2026-09-22T02:12:52.515239Z","steps":["trace[1676942606] 'process raft request' (duration: 450.011089ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:52.515502Z","caller":"traceutil/trace.go:171","msg":"trace[112741477] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"450.479941ms","start":"2026-09-22T02:12:52.065Z","end":"2026-09-22T02:12:52.51548Z","steps":["trace[112741477] 'process raft request' (duration: 450.043801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:52.515788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.064842Z","time spent":"450.595313ms","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:12:52.516085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.064964Z","time spent":"450.644744ms","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1621,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:12:52.516285Z","caller":"traceutil/trace.go:171","msg":"trace[423785232] linearizableReadLoop","detail":"{readStateIndex:6088; appliedIndex:6085; }","duration":"431.977593ms","start":"2026-09-22T02:12:52.083447Z","end":"2026-09-22T02:12:52.515425Z","steps":["trace[423785232] 'read index received' (duration: 14.230007ms)","trace[423785232] 'applied index is now lower than readState.Index' (duration: 417.746536ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:12:52.620434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.939467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:12:52.620535Z","caller":"traceutil/trace.go:171","msg":"trace[1790414106] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5866; }","duration":"537.122271ms","start":"2026-09-22T02:12:52.083386Z","end":"2026-09-22T02:12:52.620509Z","steps":["trace[1790414106] 'agreement among raft nodes before linearized reading' (duration: 432.360692ms)","trace[1790414106] 'count revisions from in-memory index tree' (duration: 104.584935ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:12:52.620594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.083363Z","time spent":"537.214802ms","remote":"127.0.0.1:48660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:12:52.620584Z","caller":"traceutil/trace.go:171","msg":"trace[1199397451] linearizableReadLoop","detail":"{readStateIndex:6089; appliedIndex:6088; }","duration":"104.117314ms","start":"2026-09-22T02:12:52.516449Z","end":"2026-09-22T02:12:52.620566Z","steps":["trace[1199397451] 'read index received' (duration: 54.199171ms)","trace[1199397451] 'applied index is now lower than readState.Index' (duration: 49.917203ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:52.620696Z","caller":"traceutil/trace.go:171","msg":"trace[736765803] transaction","detail":"{read_only:false; response_revision:5867; number_of_response:1; }","duration":"443.132047ms","start":"2026-09-22T02:12:52.177521Z","end":"2026-09-22T02:12:52.620653Z","steps":["trace[736765803] 'process raft request' (duration: 393.190525ms)","trace[736765803] 'compare' (duration: 49.703346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:12:52.620848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.274908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18d782be4a4269fc\" ","response":"range_response_count:1 size:736"} {"level":"warn","ts":"2026-09-22T02:12:52.620897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.177489Z","time spent":"443.290662ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:12:52.620908Z","caller":"traceutil/trace.go:171","msg":"trace[757891880] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18d782be4a4269fc; range_end:; response_count:1; response_revision:5867; }","duration":"519.355411ms","start":"2026-09-22T02:12:52.101534Z","end":"2026-09-22T02:12:52.62089Z","steps":["trace[757891880] 'agreement among raft nodes before linearized reading' (duration: 519.149206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:52.620947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.608087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-pgvh4\" ","response":"range_response_count:1 size:4757"} {"level":"warn","ts":"2026-09-22T02:12:52.62097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.101523Z","time spent":"519.428242ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":759,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18d782be4a4269fc\" "} {"level":"info","ts":"2026-09-22T02:12:52.621025Z","caller":"traceutil/trace.go:171","msg":"trace[344182064] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-pgvh4; range_end:; response_count:1; response_revision:5867; }","duration":"331.70269ms","start":"2026-09-22T02:12:52.289302Z","end":"2026-09-22T02:12:52.621004Z","steps":["trace[344182064] 'agreement among raft nodes before linearized reading' (duration: 331.387962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:52.621074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:12:52.289277Z","time spent":"331.789192ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":4780,"request content":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-pgvh4\" "} {"level":"info","ts":"2026-09-22T02:12:52.744766Z","caller":"traceutil/trace.go:171","msg":"trace[1180559754] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"149.943348ms","start":"2026-09-22T02:12:52.59479Z","end":"2026-09-22T02:12:52.744733Z","steps":["trace[1180559754] 'process raft request' (duration: 149.700853ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:52.909033Z","caller":"traceutil/trace.go:171","msg":"trace[1479044735] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"285.043005ms","start":"2026-09-22T02:12:52.623958Z","end":"2026-09-22T02:12:52.909001Z","steps":["trace[1479044735] 'process raft request' (duration: 236.2027ms)","trace[1479044735] 'compare' (duration: 48.607439ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:52.909223Z","caller":"traceutil/trace.go:171","msg":"trace[704321896] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"275.411448ms","start":"2026-09-22T02:12:52.633776Z","end":"2026-09-22T02:12:52.909187Z","steps":["trace[704321896] 'process raft request' (duration: 275.174282ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:52.909378Z","caller":"traceutil/trace.go:171","msg":"trace[879995685] linearizableReadLoop","detail":"{readStateIndex:6092; appliedIndex:6089; }","duration":"275.49453ms","start":"2026-09-22T02:12:52.633875Z","end":"2026-09-22T02:12:52.90937Z","steps":["trace[879995685] 'read index received' (duration: 110.646128ms)","trace[879995685] 'applied index is now lower than readState.Index' (duration: 164.846942ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:52.909482Z","caller":"traceutil/trace.go:171","msg":"trace[1605383420] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"231.593031ms","start":"2026-09-22T02:12:52.677833Z","end":"2026-09-22T02:12:52.909426Z","steps":["trace[1605383420] 'process raft request' (duration: 231.278524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:52.909704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.714196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:12:52.90981Z","caller":"traceutil/trace.go:171","msg":"trace[162222124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5871; }","duration":"188.90431ms","start":"2026-09-22T02:12:52.720866Z","end":"2026-09-22T02:12:52.909771Z","steps":["trace[162222124] 'agreement among raft nodes before linearized reading' (duration: 188.686095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:52.909955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.073933ms","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-09-22T02:12:52.909988Z","caller":"traceutil/trace.go:171","msg":"trace[136658484] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5871; }","duration":"276.185215ms","start":"2026-09-22T02:12:52.633796Z","end":"2026-09-22T02:12:52.909981Z","steps":["trace[136658484] 'agreement among raft nodes before linearized reading' (duration: 275.661402ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:53.111612Z","caller":"traceutil/trace.go:171","msg":"trace[1580630047] transaction","detail":"{read_only:false; response_revision:5873; number_of_response:1; }","duration":"195.368132ms","start":"2026-09-22T02:12:52.916191Z","end":"2026-09-22T02:12:53.111559Z","steps":["trace[1580630047] 'process raft request' (duration: 112.101282ms)","trace[1580630047] 'compare' (duration: 82.965274ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:53.111734Z","caller":"traceutil/trace.go:171","msg":"trace[1734947530] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"193.94605ms","start":"2026-09-22T02:12:52.917758Z","end":"2026-09-22T02:12:53.111704Z","steps":["trace[1734947530] 'process raft request' (duration: 193.845337ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:53.111837Z","caller":"traceutil/trace.go:171","msg":"trace[671712422] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"195.211309ms","start":"2026-09-22T02:12:52.916596Z","end":"2026-09-22T02:12:53.111807Z","steps":["trace[671712422] 'process raft request' (duration: 194.860621ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:53.111987Z","caller":"traceutil/trace.go:171","msg":"trace[566170981] transaction","detail":"{read_only:false; response_revision:5877; number_of_response:1; }","duration":"192.485886ms","start":"2026-09-22T02:12:52.919489Z","end":"2026-09-22T02:12:53.111975Z","steps":["trace[566170981] 'process raft request' (duration: 192.344412ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:53.112184Z","caller":"traceutil/trace.go:171","msg":"trace[538472519] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"193.026178ms","start":"2026-09-22T02:12:52.919088Z","end":"2026-09-22T02:12:53.112114Z","steps":["trace[538472519] 'process raft request' (duration: 192.569677ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:53.118617Z","caller":"traceutil/trace.go:171","msg":"trace[59353234] linearizableReadLoop","detail":"{readStateIndex:6100; appliedIndex:6094; }","duration":"176.604849ms","start":"2026-09-22T02:12:52.941995Z","end":"2026-09-22T02:12:53.118599Z","steps":["trace[59353234] 'read index received' (duration: 86.308592ms)","trace[59353234] 'applied index is now lower than readState.Index' (duration: 90.294957ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:53.11867Z","caller":"traceutil/trace.go:171","msg":"trace[1212022240] transaction","detail":"{read_only:false; response_revision:5878; number_of_response:1; }","duration":"191.482691ms","start":"2026-09-22T02:12:52.927156Z","end":"2026-09-22T02:12:53.118638Z","steps":["trace[1212022240] 'process raft request' (duration: 191.199514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:53.118866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.824644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:12:53.118908Z","caller":"traceutil/trace.go:171","msg":"trace[900185987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5878; }","duration":"176.951458ms","start":"2026-09-22T02:12:52.941943Z","end":"2026-09-22T02:12:53.118895Z","steps":["trace[900185987] 'agreement among raft nodes before linearized reading' (duration: 176.799495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:12:53.119067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.799368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5776eebc-1059-4d10-9cdd-ee51ccd03eb1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-22T02:12:53.119114Z","caller":"traceutil/trace.go:171","msg":"trace[792057647] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5776eebc-1059-4d10-9cdd-ee51ccd03eb1; range_end:; response_count:1; response_revision:5878; }","duration":"169.904401ms","start":"2026-09-22T02:12:52.949195Z","end":"2026-09-22T02:12:53.119099Z","steps":["trace[792057647] 'agreement among raft nodes before linearized reading' (duration: 169.652425ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:17:39.804662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5635} {"level":"info","ts":"2026-09-22T02:17:39.897008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5635,"took":"90.267998ms","hash":2389646594,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":28598272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-22T02:17:39.897096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2389646594,"revision":5635,"compact-revision":2956} {"level":"info","ts":"2026-09-22T02:19:23.621646Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002391.wal"} {"level":"info","ts":"2026-09-22T02:19:27.251948Z","caller":"traceutil/trace.go:171","msg":"trace[995445619] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"196.40262ms","start":"2026-09-22T02:19:27.055506Z","end":"2026-09-22T02:19:27.251909Z","steps":["trace[995445619] 'process raft request' (duration: 196.151165ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:19:27.43016Z","caller":"traceutil/trace.go:171","msg":"trace[820102410] linearizableReadLoop","detail":"{readStateIndex:9126; appliedIndex:9123; }","duration":"325.364307ms","start":"2026-09-22T02:19:27.104759Z","end":"2026-09-22T02:19:27.430123Z","steps":["trace[820102410] 'read index received' (duration: 146.968518ms)","trace[820102410] 'applied index is now lower than readState.Index' (duration: 178.394899ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:19:27.430194Z","caller":"traceutil/trace.go:171","msg":"trace[1435440871] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"338.018892ms","start":"2026-09-22T02:19:27.092131Z","end":"2026-09-22T02:19:27.43015Z","steps":["trace[1435440871] 'process raft request' (duration: 314.095123ms)","trace[1435440871] 'compare' (duration: 23.667283ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:19:27.430336Z","caller":"traceutil/trace.go:171","msg":"trace[1380855378] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"334.222188ms","start":"2026-09-22T02:19:27.09608Z","end":"2026-09-22T02:19:27.430302Z","steps":["trace[1380855378] 'process raft request' (duration: 333.987183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:27.430408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.625443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:19:27.430479Z","caller":"traceutil/trace.go:171","msg":"trace[1735299165] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8809; }","duration":"325.786817ms","start":"2026-09-22T02:19:27.104677Z","end":"2026-09-22T02:19:27.430464Z","steps":["trace[1735299165] 'agreement among raft nodes before linearized reading' (duration: 325.646173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:27.430528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:27.10465Z","time spent":"325.867468ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:19:27.430661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:27.096054Z","time spent":"334.362692ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:19:27.430754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:27.092098Z","time spent":"338.178466ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:19:39.957675Z","caller":"traceutil/trace.go:171","msg":"trace[2027150344] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"204.155918ms","start":"2026-09-22T02:19:39.753478Z","end":"2026-09-22T02:19:39.957634Z","steps":["trace[2027150344] 'process raft request' (duration: 203.874852ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:19:40.764041Z","caller":"traceutil/trace.go:171","msg":"trace[1996152553] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"112.915779ms","start":"2026-09-22T02:19:40.651094Z","end":"2026-09-22T02:19:40.76401Z","steps":["trace[1996152553] 'process raft request' (duration: 112.768387ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:20:10.432846Z","caller":"traceutil/trace.go:171","msg":"trace[76569967] linearizableReadLoop","detail":"{readStateIndex:9419; appliedIndex:9418; }","duration":"307.366637ms","start":"2026-09-22T02:20:10.125454Z","end":"2026-09-22T02:20:10.43282Z","steps":["trace[76569967] 'read index received' (duration: 307.079811ms)","trace[76569967] 'applied index is now lower than readState.Index' (duration: 285.766µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:20:10.432938Z","caller":"traceutil/trace.go:171","msg":"trace[149030826] transaction","detail":"{read_only:false; response_revision:9090; number_of_response:1; }","duration":"310.262061ms","start":"2026-09-22T02:20:10.12262Z","end":"2026-09-22T02:20:10.432882Z","steps":["trace[149030826] 'process raft request' (duration: 310.009895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:10.433191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:20:10.122589Z","time spent":"310.454455ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:20:10.43322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.761446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-22T02:20:10.433294Z","caller":"traceutil/trace.go:171","msg":"trace[234456494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9090; }","duration":"307.847467ms","start":"2026-09-22T02:20:10.125405Z","end":"2026-09-22T02:20:10.433253Z","steps":["trace[234456494] 'agreement among raft nodes before linearized reading' (duration: 307.658263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:10.433339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:20:10.125394Z","time spent":"307.93315ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:20:10.433517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.80837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2npfn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-22T02:20:10.433608Z","caller":"traceutil/trace.go:171","msg":"trace[1390153597] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2npfn; range_end:; response_count:1; response_revision:9090; }","duration":"251.018296ms","start":"2026-09-22T02:20:10.182565Z","end":"2026-09-22T02:20:10.433583Z","steps":["trace[1390153597] 'agreement among raft nodes before linearized reading' (duration: 250.727169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:10.43335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.881744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T02:20:10.433844Z","caller":"traceutil/trace.go:171","msg":"trace[1906696578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9090; }","duration":"306.322884ms","start":"2026-09-22T02:20:10.127386Z","end":"2026-09-22T02:20:10.433709Z","steps":["trace[1906696578] 'agreement among raft nodes before linearized reading' (duration: 305.774442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:10.433949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:20:10.127344Z","time spent":"306.59295ms","remote":"127.0.0.1:48508","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":"info","ts":"2026-09-22T02:20:13.791761Z","caller":"traceutil/trace.go:171","msg":"trace[1012598865] transaction","detail":"{read_only:false; response_revision:9109; number_of_response:1; }","duration":"108.175146ms","start":"2026-09-22T02:20:13.683534Z","end":"2026-09-22T02:20:13.791709Z","steps":["trace[1012598865] 'process raft request' (duration: 108.044734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:22.750037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.708361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:20:22.75034Z","caller":"traceutil/trace.go:171","msg":"trace[1863408605] transaction","detail":"{read_only:false; response_revision:9155; number_of_response:1; }","duration":"180.471845ms","start":"2026-09-22T02:20:22.569847Z","end":"2026-09-22T02:20:22.750318Z","steps":["trace[1863408605] 'process raft request' (duration: 69.376155ms)","trace[1863408605] 'compare' (duration: 110.400295ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:20:22.750283Z","caller":"traceutil/trace.go:171","msg":"trace[860616428] linearizableReadLoop","detail":"{readStateIndex:9486; appliedIndex:9485; }","duration":"172.492111ms","start":"2026-09-22T02:20:22.577773Z","end":"2026-09-22T02:20:22.750265Z","steps":["trace[860616428] 'read index received' (duration: 61.380451ms)","trace[860616428] 'applied index is now lower than readState.Index' (duration: 111.1103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:20:22.75065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.85105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-22T02:20:22.750728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.346735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T02:20:22.750752Z","caller":"traceutil/trace.go:171","msg":"trace[358595876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9155; }","duration":"172.983102ms","start":"2026-09-22T02:20:22.577744Z","end":"2026-09-22T02:20:22.750727Z","steps":["trace[358595876] 'agreement among raft nodes before linearized reading' (duration: 172.718227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:22.750819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.451622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:20:22.750852Z","caller":"traceutil/trace.go:171","msg":"trace[541842250] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:9155; }","duration":"101.505241ms","start":"2026-09-22T02:20:22.649338Z","end":"2026-09-22T02:20:22.750843Z","steps":["trace[541842250] 'agreement among raft nodes before linearized reading' (duration: 101.45731ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:20:22.750766Z","caller":"traceutil/trace.go:171","msg":"trace[1011744463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9155; }","duration":"170.407027ms","start":"2026-09-22T02:20:22.580348Z","end":"2026-09-22T02:20:22.750755Z","steps":["trace[1011744463] 'agreement among raft nodes before linearized reading' (duration: 170.289034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:22.75106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.348863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T02:20:22.751102Z","caller":"traceutil/trace.go:171","msg":"trace[65137995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9155; }","duration":"168.423073ms","start":"2026-09-22T02:20:22.582668Z","end":"2026-09-22T02:20:22.751091Z","steps":["trace[65137995] 'agreement among raft nodes before linearized reading' (duration: 168.330081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:22.751092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.108378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2npfn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-22T02:20:22.751212Z","caller":"traceutil/trace.go:171","msg":"trace[483122492] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2npfn; range_end:; response_count:1; response_revision:9155; }","duration":"157.261031ms","start":"2026-09-22T02:20:22.593922Z","end":"2026-09-22T02:20:22.751183Z","steps":["trace[483122492] 'agreement among raft nodes before linearized reading' (duration: 156.929885ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:20:24.90195Z","caller":"traceutil/trace.go:171","msg":"trace[1080553918] transaction","detail":"{read_only:false; response_revision:9178; number_of_response:1; }","duration":"125.869801ms","start":"2026-09-22T02:20:24.776031Z","end":"2026-09-22T02:20:24.901901Z","steps":["trace[1080553918] 'process raft request' (duration: 125.675387ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:18.048088Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:21:18.053335Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-22T02:21:18.053451Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-22T02:22:39.811461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7967} {"level":"info","ts":"2026-09-22T02:22:39.897459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7967,"took":"82.495819ms","hash":3400357599,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":25407488,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-22T02:22:39.897544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3400357599,"revision":7967,"compact-revision":5635} {"level":"info","ts":"2026-09-22T02:27:15.219965Z","caller":"traceutil/trace.go:171","msg":"trace[2115468838] linearizableReadLoop","detail":"{readStateIndex:12783; appliedIndex:12782; }","duration":"124.41609ms","start":"2026-09-22T02:27:15.095511Z","end":"2026-09-22T02:27:15.219927Z","steps":["trace[2115468838] 'read index received' (duration: 124.227965ms)","trace[2115468838] 'applied index is now lower than readState.Index' (duration: 186.775µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:27:15.220035Z","caller":"traceutil/trace.go:171","msg":"trace[1845999578] transaction","detail":"{read_only:false; response_revision:12353; number_of_response:1; }","duration":"173.430683ms","start":"2026-09-22T02:27:15.046555Z","end":"2026-09-22T02:27:15.219985Z","steps":["trace[1845999578] 'process raft request' (duration: 173.112997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:27:15.220236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.704205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T02:27:15.220281Z","caller":"traceutil/trace.go:171","msg":"trace[2105022750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12353; }","duration":"124.834349ms","start":"2026-09-22T02:27:15.095434Z","end":"2026-09-22T02:27:15.220268Z","steps":["trace[2105022750] 'agreement among raft nodes before linearized reading' (duration: 124.640025ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:27:35.807041Z","caller":"traceutil/trace.go:171","msg":"trace[209721416] transaction","detail":"{read_only:false; response_revision:12476; number_of_response:1; }","duration":"105.281705ms","start":"2026-09-22T02:27:35.70173Z","end":"2026-09-22T02:27:35.807012Z","steps":["trace[209721416] 'process raft request' (duration: 105.028039ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:27:39.819021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10358} {"level":"info","ts":"2026-09-22T02:27:39.910843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10358,"took":"88.377839ms","hash":2140512540,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":25554944,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-22T02:27:39.911249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140512540,"revision":10358,"compact-revision":7967} {"level":"info","ts":"2026-09-22T02:32:39.827151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12500} {"level":"info","ts":"2026-09-22T02:32:39.908001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12500,"took":"77.879471ms","hash":3153415859,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:32:39.908082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3153415859,"revision":12500,"compact-revision":10358} {"level":"info","ts":"2026-09-22T02:33:49.715876Z","caller":"traceutil/trace.go:171","msg":"trace[69580859] transaction","detail":"{read_only:false; response_revision:14985; number_of_response:1; }","duration":"131.430678ms","start":"2026-09-22T02:33:49.584419Z","end":"2026-09-22T02:33:49.715849Z","steps":["trace[69580859] 'process raft request' (duration: 131.273684ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:49.718595Z","caller":"traceutil/trace.go:171","msg":"trace[1010660179] transaction","detail":"{read_only:false; response_revision:14986; number_of_response:1; }","duration":"134.092985ms","start":"2026-09-22T02:33:49.584433Z","end":"2026-09-22T02:33:49.718526Z","steps":["trace[1010660179] 'process raft request' (duration: 133.929171ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:34:34.108508Z","caller":"traceutil/trace.go:171","msg":"trace[1039407331] transaction","detail":"{read_only:false; response_revision:15234; number_of_response:1; }","duration":"115.159497ms","start":"2026-09-22T02:34:33.99331Z","end":"2026-09-22T02:34:34.10847Z","steps":["trace[1039407331] 'process raft request' (duration: 114.910522ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:34:34.227535Z","caller":"traceutil/trace.go:171","msg":"trace[1590775216] transaction","detail":"{read_only:false; response_revision:15235; number_of_response:1; }","duration":"114.119854ms","start":"2026-09-22T02:34:34.113333Z","end":"2026-09-22T02:34:34.227453Z","steps":["trace[1590775216] 'process raft request' (duration: 84.239048ms)","trace[1590775216] 'compare' (duration: 29.597379ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:34:34.230789Z","caller":"traceutil/trace.go:171","msg":"trace[1611333366] transaction","detail":"{read_only:false; response_revision:15238; number_of_response:1; }","duration":"114.879601ms","start":"2026-09-22T02:34:34.115871Z","end":"2026-09-22T02:34:34.23075Z","steps":["trace[1611333366] 'process raft request' (duration: 114.411461ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:34:34.230884Z","caller":"traceutil/trace.go:171","msg":"trace[1757624726] transaction","detail":"{read_only:false; response_revision:15239; number_of_response:1; }","duration":"107.729136ms","start":"2026-09-22T02:34:34.123134Z","end":"2026-09-22T02:34:34.230863Z","steps":["trace[1757624726] 'process raft request' (duration: 107.196874ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:34:34.231036Z","caller":"traceutil/trace.go:171","msg":"trace[961040451] transaction","detail":"{read_only:false; response_revision:15236; number_of_response:1; }","duration":"116.24439ms","start":"2026-09-22T02:34:34.114772Z","end":"2026-09-22T02:34:34.231017Z","steps":["trace[961040451] 'process raft request' (duration: 115.37789ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:34:34.231156Z","caller":"traceutil/trace.go:171","msg":"trace[1008170168] transaction","detail":"{read_only:false; response_revision:15237; number_of_response:1; }","duration":"116.176098ms","start":"2026-09-22T02:34:34.114931Z","end":"2026-09-22T02:34:34.231107Z","steps":["trace[1008170168] 'process raft request' (duration: 115.31033ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:35:53.964185Z","caller":"traceutil/trace.go:171","msg":"trace[1138381066] transaction","detail":"{read_only:false; response_revision:16203; number_of_response:1; }","duration":"110.080727ms","start":"2026-09-22T02:35:53.854081Z","end":"2026-09-22T02:35:53.964162Z","steps":["trace[1138381066] 'process raft request' (duration: 109.945595ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:35:54.066576Z","caller":"traceutil/trace.go:171","msg":"trace[82838500] transaction","detail":"{read_only:false; response_revision:16204; number_of_response:1; }","duration":"189.336748ms","start":"2026-09-22T02:35:53.877197Z","end":"2026-09-22T02:35:54.066534Z","steps":["trace[82838500] 'process raft request' (duration: 175.466558ms)","trace[82838500] 'compare' (duration: 13.698126ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:36:27.707497Z","caller":"traceutil/trace.go:171","msg":"trace[984296773] transaction","detail":"{read_only:false; response_revision:16381; number_of_response:1; }","duration":"108.229268ms","start":"2026-09-22T02:36:27.598979Z","end":"2026-09-22T02:36:27.707208Z","steps":["trace[984296773] 'process raft request' (duration: 108.039503ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:36:28.210845Z","caller":"traceutil/trace.go:171","msg":"trace[237894702] transaction","detail":"{read_only:false; response_revision:16382; number_of_response:1; }","duration":"118.620481ms","start":"2026-09-22T02:36:28.092189Z","end":"2026-09-22T02:36:28.210809Z","steps":["trace[237894702] 'process raft request' (duration: 118.391616ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:37:39.834556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14525} {"level":"info","ts":"2026-09-22T02:37:39.92135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14525,"took":"82.120823ms","hash":471129984,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":30658560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:37:39.921468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":471129984,"revision":14525,"compact-revision":12500} {"level":"info","ts":"2026-09-22T02:42:39.841887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16834} {"level":"info","ts":"2026-09-22T02:42:39.947175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16834,"took":"101.176014ms","hash":135935036,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T02:42:39.947258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":135935036,"revision":16834,"compact-revision":14525} {"level":"info","ts":"2026-09-22T02:42:54.981451Z","caller":"traceutil/trace.go:171","msg":"trace[66747498] transaction","detail":"{read_only:false; response_revision:19158; number_of_response:1; }","duration":"184.728258ms","start":"2026-09-22T02:42:54.79668Z","end":"2026-09-22T02:42:54.981409Z","steps":["trace[66747498] 'process raft request' (duration: 184.545264ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:02.840581Z","caller":"traceutil/trace.go:171","msg":"trace[12403213] transaction","detail":"{read_only:false; response_revision:19204; number_of_response:1; }","duration":"247.399792ms","start":"2026-09-22T02:43:02.593149Z","end":"2026-09-22T02:43:02.840549Z","steps":["trace[12403213] 'process raft request' (duration: 247.189558ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:02.853328Z","caller":"traceutil/trace.go:171","msg":"trace[1547679069] linearizableReadLoop","detail":"{readStateIndex:19868; appliedIndex:19866; }","duration":"159.482153ms","start":"2026-09-22T02:43:02.693817Z","end":"2026-09-22T02:43:02.853299Z","steps":["trace[1547679069] 'read index received' (duration: 146.499883ms)","trace[1547679069] 'applied index is now lower than readState.Index' (duration: 12.98114ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:43:02.853418Z","caller":"traceutil/trace.go:171","msg":"trace[758038517] transaction","detail":"{read_only:false; response_revision:19205; number_of_response:1; }","duration":"235.543006ms","start":"2026-09-22T02:43:02.61783Z","end":"2026-09-22T02:43:02.853373Z","steps":["trace[758038517] 'process raft request' (duration: 235.29246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:02.853681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.832941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hbvqt\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-09-22T02:43:02.853795Z","caller":"traceutil/trace.go:171","msg":"trace[285934578] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hbvqt; range_end:; response_count:1; response_revision:19205; }","duration":"159.994824ms","start":"2026-09-22T02:43:02.693777Z","end":"2026-09-22T02:43:02.853772Z","steps":["trace[285934578] 'agreement among raft nodes before linearized reading' (duration: 159.630347ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:03.334685Z","caller":"traceutil/trace.go:171","msg":"trace[2121077961] linearizableReadLoop","detail":"{readStateIndex:19869; appliedIndex:19868; }","duration":"390.534532ms","start":"2026-09-22T02:43:02.944125Z","end":"2026-09-22T02:43:03.33466Z","steps":["trace[2121077961] 'read index received' (duration: 390.288537ms)","trace[2121077961] 'applied index is now lower than readState.Index' (duration: 244.345µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:43:03.334759Z","caller":"traceutil/trace.go:171","msg":"trace[1631338563] transaction","detail":"{read_only:false; response_revision:19206; number_of_response:1; }","duration":"458.253284ms","start":"2026-09-22T02:43:02.876468Z","end":"2026-09-22T02:43:03.334721Z","steps":["trace[1631338563] 'process raft request' (duration: 457.977988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:03.334896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.725396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:43:03.334939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:02.876439Z","time spent":"458.403557ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20864,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:43:03.334962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.144001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T02:43:03.335001Z","caller":"traceutil/trace.go:171","msg":"trace[381652662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19206; }","duration":"313.240064ms","start":"2026-09-22T02:43:03.02175Z","end":"2026-09-22T02:43:03.33499Z","steps":["trace[381652662] 'agreement among raft nodes before linearized reading' (duration: 313.07491ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:03.334991Z","caller":"traceutil/trace.go:171","msg":"trace[1963873127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19206; }","duration":"390.89394ms","start":"2026-09-22T02:43:02.944073Z","end":"2026-09-22T02:43:03.334967Z","steps":["trace[1963873127] 'agreement among raft nodes before linearized reading' (duration: 390.690556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:03.335044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:03.021729Z","time spent":"313.304846ms","remote":"127.0.0.1:48508","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-09-22T02:43:03.33506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:02.944046Z","time spent":"391.004723ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:43:03.335095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.720281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-22T02:43:03.335162Z","caller":"traceutil/trace.go:171","msg":"trace[1898498569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19206; }","duration":"167.822734ms","start":"2026-09-22T02:43:03.16732Z","end":"2026-09-22T02:43:03.335143Z","steps":["trace[1898498569] 'agreement among raft nodes before linearized reading' (duration: 167.661871ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:09.601657Z","caller":"traceutil/trace.go:171","msg":"trace[1228394130] transaction","detail":"{read_only:false; response_revision:19313; number_of_response:1; }","duration":"110.318422ms","start":"2026-09-22T02:43:09.491301Z","end":"2026-09-22T02:43:09.60162Z","steps":["trace[1228394130] 'process raft request' (duration: 108.403992ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:09.601923Z","caller":"traceutil/trace.go:171","msg":"trace[550564436] transaction","detail":"{read_only:false; response_revision:19311; number_of_response:1; }","duration":"111.585ms","start":"2026-09-22T02:43:09.490323Z","end":"2026-09-22T02:43:09.601908Z","steps":["trace[550564436] 'process raft request' (duration: 24.630862ms)","trace[550564436] 'compare' (duration: 84.485225ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:43:09.602062Z","caller":"traceutil/trace.go:171","msg":"trace[1467002526] transaction","detail":"{read_only:false; response_revision:19312; number_of_response:1; }","duration":"110.866294ms","start":"2026-09-22T02:43:09.491186Z","end":"2026-09-22T02:43:09.602052Z","steps":["trace[1467002526] 'process raft request' (duration: 108.447532ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:11.499495Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:43:11.508376Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-22T02:43:11.508532Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-22T02:47:39.857018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19052} {"level":"info","ts":"2026-09-22T02:47:39.971058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19052,"took":"110.330843ms","hash":2549070945,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":33804288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-22T02:47:39.971134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2549070945,"revision":19052,"compact-revision":16834} {"level":"warn","ts":"2026-09-22T02:49:27.968779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.276258ms","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-09-22T02:49:27.968902Z","caller":"traceutil/trace.go:171","msg":"trace[537432505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22111; }","duration":"188.476543ms","start":"2026-09-22T02:49:27.780399Z","end":"2026-09-22T02:49:27.968876Z","steps":["trace[537432505] 'range keys from in-memory index tree' (duration: 187.94914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:27.969113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.282167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T02:49:27.969236Z","caller":"traceutil/trace.go:171","msg":"trace[44175966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22111; }","duration":"125.46492ms","start":"2026-09-22T02:49:27.843744Z","end":"2026-09-22T02:49:27.969209Z","steps":["trace[44175966] 'range keys from in-memory index tree' (duration: 125.071003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:27.96927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.536595ms","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-09-22T02:49:27.969328Z","caller":"traceutil/trace.go:171","msg":"trace[2064124474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22111; }","duration":"133.633317ms","start":"2026-09-22T02:49:27.835674Z","end":"2026-09-22T02:49:27.969307Z","steps":["trace[2064124474] 'range keys from in-memory index tree' (duration: 133.2805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:27.969117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.919228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-22T02:49:27.969325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.650196ms","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-09-22T02:49:27.969466Z","caller":"traceutil/trace.go:171","msg":"trace[480579132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22111; }","duration":"207.8405ms","start":"2026-09-22T02:49:27.761565Z","end":"2026-09-22T02:49:27.969405Z","steps":["trace[480579132] 'range keys from in-memory index tree' (duration: 207.350961ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:27.969423Z","caller":"traceutil/trace.go:171","msg":"trace[1884715243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22111; }","duration":"131.273177ms","start":"2026-09-22T02:49:27.838107Z","end":"2026-09-22T02:49:27.96938Z","steps":["trace[1884715243] 'range keys from in-memory index tree' (duration: 130.683664ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:33.408267Z","caller":"traceutil/trace.go:171","msg":"trace[263507335] transaction","detail":"{read_only:false; response_revision:22142; number_of_response:1; }","duration":"130.732854ms","start":"2026-09-22T02:49:33.277503Z","end":"2026-09-22T02:49:33.408236Z","steps":["trace[263507335] 'process raft request' (duration: 130.51991ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:33.842461Z","caller":"traceutil/trace.go:171","msg":"trace[127798604] linearizableReadLoop","detail":"{readStateIndex:22904; appliedIndex:22903; }","duration":"266.907426ms","start":"2026-09-22T02:49:33.575512Z","end":"2026-09-22T02:49:33.84242Z","steps":["trace[127798604] 'read index received' (duration: 266.788093ms)","trace[127798604] 'applied index is now lower than readState.Index' (duration: 118.373µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:49:33.842714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.195403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-22T02:49:33.842894Z","caller":"traceutil/trace.go:171","msg":"trace[433713048] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:22143; }","duration":"267.388087ms","start":"2026-09-22T02:49:33.575491Z","end":"2026-09-22T02:49:33.842879Z","steps":["trace[433713048] 'agreement among raft nodes before linearized reading' (duration: 267.036529ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:33.843139Z","caller":"traceutil/trace.go:171","msg":"trace[1048329525] transaction","detail":"{read_only:false; response_revision:22143; number_of_response:1; }","duration":"366.083198ms","start":"2026-09-22T02:49:33.47639Z","end":"2026-09-22T02:49:33.842473Z","steps":["trace[1048329525] 'process raft request' (duration: 365.862264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:33.843875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:49:33.476359Z","time spent":"367.416517ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:49:33.975164Z","caller":"traceutil/trace.go:171","msg":"trace[383084229] transaction","detail":"{read_only:false; response_revision:22144; number_of_response:1; }","duration":"392.596352ms","start":"2026-09-22T02:49:33.582522Z","end":"2026-09-22T02:49:33.975118Z","steps":["trace[383084229] 'process raft request' (duration: 330.876989ms)","trace[383084229] 'compare' (duration: 61.511418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:49:33.975403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:49:33.582419Z","time spent":"392.868537ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:49:34.223173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.598656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:49:34.223676Z","caller":"traceutil/trace.go:171","msg":"trace[42252135] transaction","detail":"{read_only:false; response_revision:22145; number_of_response:1; }","duration":"161.206653ms","start":"2026-09-22T02:49:34.06244Z","end":"2026-09-22T02:49:34.223646Z","steps":["trace[42252135] 'process raft request' (duration: 21.017094ms)","trace[42252135] 'compare' (duration: 139.33165ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:49:34.224212Z","caller":"traceutil/trace.go:171","msg":"trace[1833577264] transaction","detail":"{read_only:false; response_revision:22147; number_of_response:1; }","duration":"151.198907ms","start":"2026-09-22T02:49:34.073Z","end":"2026-09-22T02:49:34.224199Z","steps":["trace[1833577264] 'process raft request' (duration: 151.156356ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:34.22446Z","caller":"traceutil/trace.go:171","msg":"trace[2117820625] transaction","detail":"{read_only:false; response_revision:22146; number_of_response:1; }","duration":"155.115451ms","start":"2026-09-22T02:49:34.069261Z","end":"2026-09-22T02:49:34.224377Z","steps":["trace[2117820625] 'process raft request' (duration: 154.812344ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:34.224534Z","caller":"traceutil/trace.go:171","msg":"trace[92673118] linearizableReadLoop","detail":"{readStateIndex:22907; appliedIndex:22906; }","duration":"156.463829ms","start":"2026-09-22T02:49:34.068056Z","end":"2026-09-22T02:49:34.22452Z","steps":["trace[92673118] 'read index received' (duration: 15.336091ms)","trace[92673118] 'applied index is now lower than readState.Index' (duration: 141.126338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:49:34.224765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.078215ms","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-09-22T02:49:34.224818Z","caller":"traceutil/trace.go:171","msg":"trace[1584815509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22147; }","duration":"208.155127ms","start":"2026-09-22T02:49:34.016646Z","end":"2026-09-22T02:49:34.224801Z","steps":["trace[1584815509] 'agreement among raft nodes before linearized reading' (duration: 207.996213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.22497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.268791ms","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":"info","ts":"2026-09-22T02:49:34.225023Z","caller":"traceutil/trace.go:171","msg":"trace[1913518888] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:22147; }","duration":"135.358094ms","start":"2026-09-22T02:49:34.089649Z","end":"2026-09-22T02:49:34.225008Z","steps":["trace[1913518888] 'agreement among raft nodes before linearized reading' (duration: 135.277613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.22504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.71286ms","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-09-22T02:49:34.225143Z","caller":"traceutil/trace.go:171","msg":"trace[1503976723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22147; }","duration":"221.875433ms","start":"2026-09-22T02:49:34.003243Z","end":"2026-09-22T02:49:34.225118Z","steps":["trace[1503976723] 'agreement among raft nodes before linearized reading' (duration: 221.576426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.225228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.231903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T02:49:34.225345Z","caller":"traceutil/trace.go:171","msg":"trace[1684697264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22147; }","duration":"211.485639ms","start":"2026-09-22T02:49:34.013839Z","end":"2026-09-22T02:49:34.225325Z","steps":["trace[1684697264] 'agreement among raft nodes before linearized reading' (duration: 211.07647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.225296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.986774ms","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-09-22T02:49:34.225634Z","caller":"traceutil/trace.go:171","msg":"trace[1021458975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22147; }","duration":"209.358273ms","start":"2026-09-22T02:49:34.01626Z","end":"2026-09-22T02:49:34.225618Z","steps":["trace[1021458975] 'agreement among raft nodes before linearized reading' (duration: 208.592146ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:34.498361Z","caller":"traceutil/trace.go:171","msg":"trace[683144668] transaction","detail":"{read_only:false; response_revision:22149; number_of_response:1; }","duration":"266.404125ms","start":"2026-09-22T02:49:34.231922Z","end":"2026-09-22T02:49:34.498326Z","steps":["trace[683144668] 'process raft request' (duration: 266.084578ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:34.498851Z","caller":"traceutil/trace.go:171","msg":"trace[663054397] transaction","detail":"{read_only:false; response_revision:22150; number_of_response:1; }","duration":"266.886326ms","start":"2026-09-22T02:49:34.231918Z","end":"2026-09-22T02:49:34.498805Z","steps":["trace[663054397] 'process raft request' (duration: 266.347894ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:49:34.498862Z","caller":"traceutil/trace.go:171","msg":"trace[75179378] linearizableReadLoop","detail":"{readStateIndex:22913; appliedIndex:22910; }","duration":"265.790242ms","start":"2026-09-22T02:49:34.233041Z","end":"2026-09-22T02:49:34.498832Z","steps":["trace[75179378] 'read index received' (duration: 58.126576ms)","trace[75179378] 'applied index is now lower than readState.Index' (duration: 207.662646ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:49:34.498933Z","caller":"traceutil/trace.go:171","msg":"trace[1867761217] transaction","detail":"{read_only:false; response_revision:22151; number_of_response:1; }","duration":"266.071597ms","start":"2026-09-22T02:49:34.232831Z","end":"2026-09-22T02:49:34.498902Z","steps":["trace[1867761217] 'process raft request' (duration: 265.859793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.49944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.367674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T02:49:34.499506Z","caller":"traceutil/trace.go:171","msg":"trace[1013353218] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:22151; }","duration":"266.473986ms","start":"2026-09-22T02:49:34.233019Z","end":"2026-09-22T02:49:34.499493Z","steps":["trace[1013353218] 'agreement among raft nodes before linearized reading' (duration: 265.959845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:49:34.512367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.600777ms","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-09-22T02:49:34.512482Z","caller":"traceutil/trace.go:171","msg":"trace[1058308406] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:22152; }","duration":"139.74444ms","start":"2026-09-22T02:49:34.372686Z","end":"2026-09-22T02:49:34.512431Z","steps":["trace[1058308406] 'agreement among raft nodes before linearized reading' (duration: 139.484535ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:52:39.865773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21357} {"level":"info","ts":"2026-09-22T02:52:39.989231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21357,"took":"117.693692ms","hash":4260594646,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":30171136,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T02:52:39.989313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260594646,"revision":21357,"compact-revision":19052} {"level":"info","ts":"2026-09-22T02:53:56.120256Z","caller":"traceutil/trace.go:171","msg":"trace[2012284707] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"225.478172ms","start":"2026-09-22T02:53:55.894739Z","end":"2026-09-22T02:53:56.120217Z","steps":["trace[2012284707] 'process raft request' (duration: 222.630691ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:55:19.244402Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067a3.wal"} {"level":"info","ts":"2026-09-22T02:56:33.96091Z","caller":"traceutil/trace.go:171","msg":"trace[389773908] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"116.22544ms","start":"2026-09-22T02:56:33.844638Z","end":"2026-09-22T02:56:33.960863Z","steps":["trace[389773908] 'process raft request' (duration: 116.045436ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:39.95326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23589} {"level":"info","ts":"2026-09-22T02:57:40.077006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23589,"took":"116.300223ms","hash":3082388600,"current-db-size-bytes":56377344,"current-db-size":"56 MB","current-db-size-in-use-bytes":45338624,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-22T02:57:40.077098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3082388600,"revision":23589,"compact-revision":21357} {"level":"info","ts":"2026-09-22T02:57:41.016707Z","caller":"traceutil/trace.go:171","msg":"trace[471006551] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"167.420757ms","start":"2026-09-22T02:57:40.849255Z","end":"2026-09-22T02:57:41.016676Z","steps":["trace[471006551] 'process raft request' (duration: 167.229762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:41.240625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.23772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:57:41.240851Z","caller":"traceutil/trace.go:171","msg":"trace[832655168] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"353.591529ms","start":"2026-09-22T02:57:40.887213Z","end":"2026-09-22T02:57:41.240805Z","steps":["trace[832655168] 'process raft request' (duration: 244.094223ms)","trace[832655168] 'compare' (duration: 109.059596ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:41.240991Z","caller":"traceutil/trace.go:171","msg":"trace[236637165] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"324.192583ms","start":"2026-09-22T02:57:40.916783Z","end":"2026-09-22T02:57:41.240976Z","steps":["trace[236637165] 'process raft request' (duration: 323.939308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:41.241042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:40.916763Z","time spent":"324.258625ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:41.24106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:40.887186Z","time spent":"353.756742ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:41.241203Z","caller":"traceutil/trace.go:171","msg":"trace[718543311] linearizableReadLoop","detail":"{readStateIndex:27870; appliedIndex:27868; }","duration":"339.475174ms","start":"2026-09-22T02:57:40.901704Z","end":"2026-09-22T02:57:41.241179Z","steps":["trace[718543311] 'read index received' (duration: 114.885633ms)","trace[718543311] 'applied index is now lower than readState.Index' (duration: 224.588181ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:41.241297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.591437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:41.241322Z","caller":"traceutil/trace.go:171","msg":"trace[1970328441] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26973; }","duration":"339.648848ms","start":"2026-09-22T02:57:40.901667Z","end":"2026-09-22T02:57:41.241316Z","steps":["trace[1970328441] 'agreement among raft nodes before linearized reading' (duration: 339.599007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:41.241342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:40.90165Z","time spent":"339.688649ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:57:41.241388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.782082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:41.241427Z","caller":"traceutil/trace.go:171","msg":"trace[1694022752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26973; }","duration":"302.866114ms","start":"2026-09-22T02:57:40.938551Z","end":"2026-09-22T02:57:41.241417Z","steps":["trace[1694022752] 'agreement among raft nodes before linearized reading' (duration: 302.786703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:41.24149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:40.938538Z","time spent":"302.943855ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:57:41.241585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.302772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:41.241622Z","caller":"traceutil/trace.go:171","msg":"trace[1404619904] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26973; }","duration":"259.379815ms","start":"2026-09-22T02:57:40.982236Z","end":"2026-09-22T02:57:41.241616Z","steps":["trace[1404619904] 'agreement among raft nodes before linearized reading' (duration: 259.332224ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.1627Z","caller":"traceutil/trace.go:171","msg":"trace[1628995474] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"118.787216ms","start":"2026-09-22T02:57:45.043885Z","end":"2026-09-22T02:57:45.162672Z","steps":["trace[1628995474] 'process raft request' (duration: 118.567011ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.756922Z","caller":"traceutil/trace.go:171","msg":"trace[1955886261] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"256.989213ms","start":"2026-09-22T02:57:45.499899Z","end":"2026-09-22T02:57:45.756889Z","steps":["trace[1955886261] 'process raft request' (duration: 256.772038ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.787538Z","caller":"traceutil/trace.go:171","msg":"trace[253685311] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"192.513319ms","start":"2026-09-22T02:57:45.594996Z","end":"2026-09-22T02:57:45.787509Z","steps":["trace[253685311] 'process raft request' (duration: 192.333494ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.787922Z","caller":"traceutil/trace.go:171","msg":"trace[886711827] linearizableReadLoop","detail":"{readStateIndex:27903; appliedIndex:27901; }","duration":"176.892522ms","start":"2026-09-22T02:57:45.611Z","end":"2026-09-22T02:57:45.787893Z","steps":["trace[886711827] 'read index received' (duration: 145.705678ms)","trace[886711827] 'applied index is now lower than readState.Index' (duration: 31.182774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:45.788242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.219559ms","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-09-22T02:57:45.788333Z","caller":"traceutil/trace.go:171","msg":"trace[919217151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27004; }","duration":"177.362282ms","start":"2026-09-22T02:57:45.610951Z","end":"2026-09-22T02:57:45.788313Z","steps":["trace[919217151] 'agreement among raft nodes before linearized reading' (duration: 177.083135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:45.788366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.147128ms","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":"info","ts":"2026-09-22T02:57:45.788609Z","caller":"traceutil/trace.go:171","msg":"trace[767614146] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:27004; }","duration":"103.475386ms","start":"2026-09-22T02:57:45.685104Z","end":"2026-09-22T02:57:45.78858Z","steps":["trace[767614146] 'agreement among raft nodes before linearized reading' (duration: 103.108048ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.916117Z","caller":"traceutil/trace.go:171","msg":"trace[1095708784] transaction","detail":"{read_only:false; response_revision:27006; number_of_response:1; }","duration":"121.899474ms","start":"2026-09-22T02:57:45.79419Z","end":"2026-09-22T02:57:45.91609Z","steps":["trace[1095708784] 'process raft request' (duration: 121.836512ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:45.916483Z","caller":"traceutil/trace.go:171","msg":"trace[441176123] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"122.68156ms","start":"2026-09-22T02:57:45.793777Z","end":"2026-09-22T02:57:45.916458Z","steps":["trace[441176123] 'process raft request' (duration: 111.115351ms)","trace[441176123] 'compare' (duration: 10.557517ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:46.15645Z","caller":"traceutil/trace.go:171","msg":"trace[448978041] transaction","detail":"{read_only:false; response_revision:27007; number_of_response:1; }","duration":"157.935792ms","start":"2026-09-22T02:57:45.998469Z","end":"2026-09-22T02:57:46.156405Z","steps":["trace[448978041] 'process raft request' (duration: 144.534022ms)","trace[448978041] 'compare' (duration: 13.053262ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:46.42618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.798759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:57:46.426453Z","caller":"traceutil/trace.go:171","msg":"trace[1279027161] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"426.269439ms","start":"2026-09-22T02:57:46.000133Z","end":"2026-09-22T02:57:46.426402Z","steps":["trace[1279027161] 'process raft request' (duration: 274.129893ms)","trace[1279027161] 'compare' (duration: 151.506352ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:46.426586Z","caller":"traceutil/trace.go:171","msg":"trace[827328458] transaction","detail":"{read_only:false; response_revision:27011; number_of_response:1; }","duration":"129.984839ms","start":"2026-09-22T02:57:46.29658Z","end":"2026-09-22T02:57:46.426565Z","steps":["trace[827328458] 'process raft request' (duration: 129.914827ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:46.426589Z","caller":"traceutil/trace.go:171","msg":"trace[560408439] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"424.346897ms","start":"2026-09-22T02:57:46.002189Z","end":"2026-09-22T02:57:46.426536Z","steps":["trace[560408439] 'process raft request' (duration: 424.132452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:46.426652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:46.000096Z","time spent":"426.444272ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:46.426805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:46.002159Z","time spent":"424.537691ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:46.426847Z","caller":"traceutil/trace.go:171","msg":"trace[2112325999] linearizableReadLoop","detail":"{readStateIndex:27909; appliedIndex:27906; }","duration":"380.91526ms","start":"2026-09-22T02:57:46.045918Z","end":"2026-09-22T02:57:46.426833Z","steps":["trace[2112325999] 'read index received' (duration: 97.074487ms)","trace[2112325999] 'applied index is now lower than readState.Index' (duration: 283.839163ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:46.426926Z","caller":"traceutil/trace.go:171","msg":"trace[1837945598] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"347.954287ms","start":"2026-09-22T02:57:46.078961Z","end":"2026-09-22T02:57:46.426916Z","steps":["trace[1837945598] 'process raft request' (duration: 347.480936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:46.426993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:46.078935Z","time spent":"348.019898ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:46.427081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.133944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:57:46.427175Z","caller":"traceutil/trace.go:171","msg":"trace[429285210] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:27011; }","duration":"381.295908ms","start":"2026-09-22T02:57:46.045857Z","end":"2026-09-22T02:57:46.427153Z","steps":["trace[429285210] 'agreement among raft nodes before linearized reading' (duration: 381.097884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:46.427248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:46.045825Z","time spent":"381.411759ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":14,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:57:46.445906Z","caller":"traceutil/trace.go:171","msg":"trace[133036514] transaction","detail":"{read_only:false; response_revision:27012; number_of_response:1; }","duration":"116.351584ms","start":"2026-09-22T02:57:46.329521Z","end":"2026-09-22T02:57:46.445872Z","steps":["trace[133036514] 'process raft request' (duration: 116.16806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:46.791957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.095227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:46.79208Z","caller":"traceutil/trace.go:171","msg":"trace[14693108] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:27012; }","duration":"116.278262ms","start":"2026-09-22T02:57:46.675772Z","end":"2026-09-22T02:57:46.79205Z","steps":["trace[14693108] 'count revisions from in-memory index tree' (duration: 116.019875ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:47.552798Z","caller":"traceutil/trace.go:171","msg":"trace[1221188540] linearizableReadLoop","detail":"{readStateIndex:27914; appliedIndex:27912; }","duration":"204.15135ms","start":"2026-09-22T02:57:47.348606Z","end":"2026-09-22T02:57:47.552758Z","steps":["trace[1221188540] 'read index received' (duration: 2.77069ms)","trace[1221188540] 'applied index is now lower than readState.Index' (duration: 201.37887ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:47.552858Z","caller":"traceutil/trace.go:171","msg":"trace[1288696379] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"216.967357ms","start":"2026-09-22T02:57:47.335855Z","end":"2026-09-22T02:57:47.552823Z","steps":["trace[1288696379] 'process raft request' (duration: 216.643751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:47.553018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.378316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:47.554098Z","caller":"traceutil/trace.go:171","msg":"trace[1310030841] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:27014; }","duration":"205.594522ms","start":"2026-09-22T02:57:47.348486Z","end":"2026-09-22T02:57:47.554081Z","steps":["trace[1310030841] 'agreement among raft nodes before linearized reading' (duration: 204.439507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:47.554502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.851989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:57:47.554642Z","caller":"traceutil/trace.go:171","msg":"trace[716274943] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:27014; }","duration":"101.297929ms","start":"2026-09-22T02:57:47.453303Z","end":"2026-09-22T02:57:47.554601Z","steps":["trace[716274943] 'agreement among raft nodes before linearized reading' (duration: 100.744267ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:47.937035Z","caller":"traceutil/trace.go:171","msg":"trace[966686225] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"174.191024ms","start":"2026-09-22T02:57:47.762795Z","end":"2026-09-22T02:57:47.936986Z","steps":["trace[966686225] 'process raft request' (duration: 121.499436ms)","trace[966686225] 'compare' (duration: 51.683576ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:48.019664Z","caller":"traceutil/trace.go:171","msg":"trace[585795561] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"224.386358ms","start":"2026-09-22T02:57:47.79517Z","end":"2026-09-22T02:57:48.019556Z","steps":["trace[585795561] 'process raft request' (duration: 224.324706ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:48.01971Z","caller":"traceutil/trace.go:171","msg":"trace[881339394] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"225.921681ms","start":"2026-09-22T02:57:47.793732Z","end":"2026-09-22T02:57:48.019653Z","steps":["trace[881339394] 'process raft request' (duration: 225.513212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.225962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.351101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:57:48.226063Z","caller":"traceutil/trace.go:171","msg":"trace[441019874] linearizableReadLoop","detail":"{readStateIndex:27918; appliedIndex:27915; }","duration":"306.091653ms","start":"2026-09-22T02:57:47.919955Z","end":"2026-09-22T02:57:48.226047Z","steps":["trace[441019874] 'read index received' (duration: 99.323466ms)","trace[441019874] 'applied index is now lower than readState.Index' (duration: 206.766597ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:48.226158Z","caller":"traceutil/trace.go:171","msg":"trace[1561154097] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"339.178108ms","start":"2026-09-22T02:57:47.886967Z","end":"2026-09-22T02:57:48.226145Z","steps":["trace[1561154097] 'process raft request' (duration: 155.214084ms)","trace[1561154097] 'compare' (duration: 182.739928ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:48.226211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:47.886943Z","time spent":"339.241829ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:48.22649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.533282ms","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-09-22T02:57:48.226514Z","caller":"traceutil/trace.go:171","msg":"trace[1510153167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27018; }","duration":"306.559443ms","start":"2026-09-22T02:57:47.919948Z","end":"2026-09-22T02:57:48.226508Z","steps":["trace[1510153167] 'agreement among raft nodes before linearized reading' (duration: 306.41976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.226531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:47.919896Z","time spent":"306.630394ms","remote":"127.0.0.1:48508","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-09-22T02:57:48.226687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.218515ms","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-09-22T02:57:48.226714Z","caller":"traceutil/trace.go:171","msg":"trace[1791949573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27018; }","duration":"306.246956ms","start":"2026-09-22T02:57:47.920462Z","end":"2026-09-22T02:57:48.226709Z","steps":["trace[1791949573] 'agreement among raft nodes before linearized reading' (duration: 306.158285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.226733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:47.920377Z","time spent":"306.352468ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:57:48.226959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.386997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:48.226985Z","caller":"traceutil/trace.go:171","msg":"trace[1118979935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27018; }","duration":"286.458478ms","start":"2026-09-22T02:57:47.94052Z","end":"2026-09-22T02:57:48.226978Z","steps":["trace[1118979935] 'agreement among raft nodes before linearized reading' (duration: 286.421227ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:48.638491Z","caller":"traceutil/trace.go:171","msg":"trace[796766922] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"402.132468ms","start":"2026-09-22T02:57:48.236323Z","end":"2026-09-22T02:57:48.638456Z","steps":["trace[796766922] 'process raft request' (duration: 401.634997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.638692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.236295Z","time spent":"402.273541ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.884123Z","caller":"traceutil/trace.go:171","msg":"trace[15761351] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"647.631062ms","start":"2026-09-22T02:57:48.236466Z","end":"2026-09-22T02:57:48.884097Z","steps":["trace[15761351] 'process raft request' (duration: 647.377396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.884292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.236427Z","time spent":"647.778163ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.88461Z","caller":"traceutil/trace.go:171","msg":"trace[1582337561] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"647.869228ms","start":"2026-09-22T02:57:48.236728Z","end":"2026-09-22T02:57:48.884597Z","steps":["trace[1582337561] 'process raft request' (duration: 647.292725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.884695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.236698Z","time spent":"647.961938ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.885733Z","caller":"traceutil/trace.go:171","msg":"trace[734776158] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"453.886266ms","start":"2026-09-22T02:57:48.431829Z","end":"2026-09-22T02:57:48.885715Z","steps":["trace[734776158] 'process raft request' (duration: 453.772313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.885823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.431796Z","time spent":"453.982968ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.885864Z","caller":"traceutil/trace.go:171","msg":"trace[1953697349] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"462.429672ms","start":"2026-09-22T02:57:48.423373Z","end":"2026-09-22T02:57:48.885803Z","steps":["trace[1953697349] 'process raft request' (duration: 461.459401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.886108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.423343Z","time spent":"462.635935ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.886086Z","caller":"traceutil/trace.go:171","msg":"trace[1713534938] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"450.649245ms","start":"2026-09-22T02:57:48.435405Z","end":"2026-09-22T02:57:48.886054Z","steps":["trace[1713534938] 'process raft request' (duration: 450.257467ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:48.886261Z","caller":"traceutil/trace.go:171","msg":"trace[242321155] linearizableReadLoop","detail":"{readStateIndex:27921; appliedIndex:27918; }","duration":"497.686472ms","start":"2026-09-22T02:57:48.388559Z","end":"2026-09-22T02:57:48.886245Z","steps":["trace[242321155] 'read index received' (duration: 249.642283ms)","trace[242321155] 'applied index is now lower than readState.Index' (duration: 248.043189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:48.886268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.435386Z","time spent":"450.779518ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:48.886404Z","caller":"traceutil/trace.go:171","msg":"trace[259313103] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"434.513527ms","start":"2026-09-22T02:57:48.451882Z","end":"2026-09-22T02:57:48.886395Z","steps":["trace[259313103] 'process raft request' (duration: 434.059287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.886467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.451849Z","time spent":"434.584089ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:48.886579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.014659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-09-22T02:57:48.886683Z","caller":"traceutil/trace.go:171","msg":"trace[1337194327] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:27025; }","duration":"498.104571ms","start":"2026-09-22T02:57:48.388517Z","end":"2026-09-22T02:57:48.886622Z","steps":["trace[1337194327] 'agreement among raft nodes before linearized reading' (duration: 497.895177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:48.886729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.388495Z","time spent":"498.220303ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-22T02:57:49.01225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.223531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T02:57:49.013022Z","caller":"traceutil/trace.go:171","msg":"trace[18869311] linearizableReadLoop","detail":"{readStateIndex:27927; appliedIndex:27925; }","duration":"126.693986ms","start":"2026-09-22T02:57:48.886297Z","end":"2026-09-22T02:57:49.012991Z","steps":["trace[18869311] 'read index received' (duration: 9.71957ms)","trace[18869311] 'applied index is now lower than readState.Index' (duration: 116.972036ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:49.013139Z","caller":"traceutil/trace.go:171","msg":"trace[1925250402] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"265.871104ms","start":"2026-09-22T02:57:48.747218Z","end":"2026-09-22T02:57:49.013089Z","steps":["trace[1925250402] 'process raft request' (duration: 265.23323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.159659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.331767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1b108d6c-2cae-4d08-aa52-c38c604b5287\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-09-22T02:57:49.159784Z","caller":"traceutil/trace.go:171","msg":"trace[502715377] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1b108d6c-2cae-4d08-aa52-c38c604b5287; range_end:; response_count:1; response_revision:27026; }","duration":"268.579751ms","start":"2026-09-22T02:57:48.891176Z","end":"2026-09-22T02:57:49.159755Z","steps":["trace[502715377] 'agreement among raft nodes before linearized reading' (duration: 122.015676ms)","trace[502715377] 'range keys from in-memory index tree' (duration: 146.235429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.159987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.111115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:49.160111Z","caller":"traceutil/trace.go:171","msg":"trace[1307331697] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:27026; }","duration":"114.254488ms","start":"2026-09-22T02:57:49.045829Z","end":"2026-09-22T02:57:49.160083Z","steps":["trace[1307331697] 'count revisions from in-memory index tree' (duration: 113.981643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.160199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.653535ms","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-09-22T02:57:49.160295Z","caller":"traceutil/trace.go:171","msg":"trace[887428721] 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:27026; }","duration":"711.782377ms","start":"2026-09-22T02:57:48.448485Z","end":"2026-09-22T02:57:49.160267Z","steps":["trace[887428721] 'agreement among raft nodes before linearized reading' (duration: 564.650919ms)","trace[887428721] 'range keys from in-memory index tree' (duration: 146.994156ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.160341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.448474Z","time spent":"711.855649ms","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-22T02:57:49.160374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.377733ms","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-09-22T02:57:49.160429Z","caller":"traceutil/trace.go:171","msg":"trace[1698232249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27026; }","duration":"726.462665ms","start":"2026-09-22T02:57:48.433948Z","end":"2026-09-22T02:57:49.160411Z","steps":["trace[1698232249] 'agreement among raft nodes before linearized reading' (duration: 579.210624ms)","trace[1698232249] 'range keys from in-memory index tree' (duration: 147.108558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.160481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.433929Z","time spent":"726.537656ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T02:57:49.160222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.584207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:57:49.160862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.687966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-22T02:57:49.160877Z","caller":"traceutil/trace.go:171","msg":"trace[602175799] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:27026; }","duration":"433.2759ms","start":"2026-09-22T02:57:48.727575Z","end":"2026-09-22T02:57:49.160851Z","steps":["trace[602175799] 'agreement among raft nodes before linearized reading' (duration: 285.454406ms)","trace[602175799] 'count revisions from in-memory index tree' (duration: 147.132779ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:49.160898Z","caller":"traceutil/trace.go:171","msg":"trace[1927095953] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27026; }","duration":"655.759257ms","start":"2026-09-22T02:57:48.505127Z","end":"2026-09-22T02:57:49.160886Z","steps":["trace[1927095953] 'agreement among raft nodes before linearized reading' (duration: 507.967274ms)","trace[1927095953] 'range keys from in-memory index tree' (duration: 147.631599ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.160929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.505105Z","time spent":"655.817098ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-22T02:57:49.160935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.727554Z","time spent":"433.361363ms","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:57:49.160959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.450843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:49.161034Z","caller":"traceutil/trace.go:171","msg":"trace[490607710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27026; }","duration":"220.567696ms","start":"2026-09-22T02:57:48.94044Z","end":"2026-09-22T02:57:49.161008Z","steps":["trace[490607710] 'agreement among raft nodes before linearized reading' (duration: 72.754463ms)","trace[490607710] 'range keys from in-memory index tree' (duration: 147.694961ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.161071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.781506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:49.161184Z","caller":"traceutil/trace.go:171","msg":"trace[1960335553] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:27026; }","duration":"505.990171ms","start":"2026-09-22T02:57:48.655161Z","end":"2026-09-22T02:57:49.161151Z","steps":["trace[1960335553] 'agreement among raft nodes before linearized reading' (duration: 357.980894ms)","trace[1960335553] 'count revisions from in-memory index tree' (duration: 147.828353ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.161249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.655129Z","time spent":"506.102423ms","remote":"127.0.0.1:52036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:57:49.16123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.428178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T02:57:49.161365Z","caller":"traceutil/trace.go:171","msg":"trace[48712494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27026; }","duration":"729.589953ms","start":"2026-09-22T02:57:48.431748Z","end":"2026-09-22T02:57:49.161338Z","steps":["trace[48712494] 'agreement among raft nodes before linearized reading' (duration: 581.433302ms)","trace[48712494] 'range keys from in-memory index tree' (duration: 147.825484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:49.161422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:48.431615Z","time spent":"729.797016ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:57:49.326682Z","caller":"traceutil/trace.go:171","msg":"trace[1562378064] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"145.274458ms","start":"2026-09-22T02:57:49.181375Z","end":"2026-09-22T02:57:49.32665Z","steps":["trace[1562378064] 'process raft request' (duration: 145.036113ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:49.327454Z","caller":"traceutil/trace.go:171","msg":"trace[277338896] linearizableReadLoop","detail":"{readStateIndex:27929; appliedIndex:27927; }","duration":"144.226006ms","start":"2026-09-22T02:57:49.183202Z","end":"2026-09-22T02:57:49.327428Z","steps":["trace[277338896] 'read index received' (duration: 143.100412ms)","trace[277338896] 'applied index is now lower than readState.Index' (duration: 1.124304ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:49.327533Z","caller":"traceutil/trace.go:171","msg":"trace[1108731209] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"146.131317ms","start":"2026-09-22T02:57:49.181378Z","end":"2026-09-22T02:57:49.32751Z","steps":["trace[1108731209] 'process raft request' (duration: 145.893432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.327717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.499772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"warn","ts":"2026-09-22T02:57:49.327718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.717789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:49.327778Z","caller":"traceutil/trace.go:171","msg":"trace[1044844169] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27028; }","duration":"144.610624ms","start":"2026-09-22T02:57:49.183146Z","end":"2026-09-22T02:57:49.327757Z","steps":["trace[1044844169] 'agreement among raft nodes before linearized reading' (duration: 144.399639ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:49.327782Z","caller":"traceutil/trace.go:171","msg":"trace[1180903799] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27028; }","duration":"139.863861ms","start":"2026-09-22T02:57:49.187901Z","end":"2026-09-22T02:57:49.327765Z","steps":["trace[1180903799] 'agreement among raft nodes before linearized reading' (duration: 139.715218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.512393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.003535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:57:49.512976Z","caller":"traceutil/trace.go:171","msg":"trace[367954702] transaction","detail":"{read_only:false; response_revision:27029; number_of_response:1; }","duration":"155.282395ms","start":"2026-09-22T02:57:49.357665Z","end":"2026-09-22T02:57:49.512948Z","steps":["trace[367954702] 'process raft request' (duration: 33.609966ms)","trace[367954702] 'compare' (duration: 120.851961ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:49.859729Z","caller":"traceutil/trace.go:171","msg":"trace[1922457021] linearizableReadLoop","detail":"{readStateIndex:27931; appliedIndex:27930; }","duration":"324.081031ms","start":"2026-09-22T02:57:49.535604Z","end":"2026-09-22T02:57:49.859685Z","steps":["trace[1922457021] 'read index received' (duration: 323.814915ms)","trace[1922457021] 'applied index is now lower than readState.Index' (duration: 264.036µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:49.859893Z","caller":"traceutil/trace.go:171","msg":"trace[550810948] transaction","detail":"{read_only:false; response_revision:27030; number_of_response:1; }","duration":"325.87257ms","start":"2026-09-22T02:57:49.533969Z","end":"2026-09-22T02:57:49.859842Z","steps":["trace[550810948] 'process raft request' (duration: 325.423161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.860042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.407329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-09-22T02:57:49.860105Z","caller":"traceutil/trace.go:171","msg":"trace[426020727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27030; }","duration":"324.555372ms","start":"2026-09-22T02:57:49.535528Z","end":"2026-09-22T02:57:49.860083Z","steps":["trace[426020727] 'agreement among raft nodes before linearized reading' (duration: 324.309346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.860136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:49.533943Z","time spent":"326.080354ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:49.860159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:49.535495Z","time spent":"324.652494ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19050,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-22T02:57:49.879703Z","caller":"traceutil/trace.go:171","msg":"trace[1834224882] transaction","detail":"{read_only:false; response_revision:27031; number_of_response:1; }","duration":"320.566015ms","start":"2026-09-22T02:57:49.5591Z","end":"2026-09-22T02:57:49.879666Z","steps":["trace[1834224882] 'process raft request' (duration: 320.262609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:49.879911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:49.559067Z","time spent":"320.717829ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:50.377986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.8437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:57:50.378215Z","caller":"traceutil/trace.go:171","msg":"trace[1337615487] linearizableReadLoop","detail":"{readStateIndex:27935; appliedIndex:27932; }","duration":"247.102928ms","start":"2026-09-22T02:57:50.131088Z","end":"2026-09-22T02:57:50.378191Z","steps":["trace[1337615487] 'read index received' (duration: 80.99778ms)","trace[1337615487] 'applied index is now lower than readState.Index' (duration: 166.103968ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:50.378289Z","caller":"traceutil/trace.go:171","msg":"trace[1124842205] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"341.076368ms","start":"2026-09-22T02:57:50.037194Z","end":"2026-09-22T02:57:50.37827Z","steps":["trace[1124842205] 'process raft request' (duration: 340.960186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:50.378331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.23853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:50.378353Z","caller":"traceutil/trace.go:171","msg":"trace[1809034646] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:27034; }","duration":"247.262761ms","start":"2026-09-22T02:57:50.13108Z","end":"2026-09-22T02:57:50.378343Z","steps":["trace[1809034646] 'agreement among raft nodes before linearized reading' (duration: 247.186639ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:50.378332Z","caller":"traceutil/trace.go:171","msg":"trace[330221761] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"348.125721ms","start":"2026-09-22T02:57:50.030148Z","end":"2026-09-22T02:57:50.378274Z","steps":["trace[330221761] 'process raft request' (duration: 347.946718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:50.378369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:50.037119Z","time spent":"341.214832ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:50.378542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:50.030119Z","time spent":"348.289995ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:50.378878Z","caller":"traceutil/trace.go:171","msg":"trace[864498724] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"433.67593ms","start":"2026-09-22T02:57:49.945189Z","end":"2026-09-22T02:57:50.378865Z","steps":["trace[864498724] 'process raft request' (duration: 112.868649ms)","trace[864498724] 'compare' (duration: 319.618065ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:50.378936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:49.945171Z","time spent":"433.738622ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:57:50.4043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.564021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:50.404412Z","caller":"traceutil/trace.go:171","msg":"trace[1156869451] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:27035; }","duration":"218.773525ms","start":"2026-09-22T02:57:50.18561Z","end":"2026-09-22T02:57:50.404384Z","steps":["trace[1156869451] 'agreement among raft nodes before linearized reading' (duration: 218.50221ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:50.404442Z","caller":"traceutil/trace.go:171","msg":"trace[1323830681] transaction","detail":"{read_only:false; response_revision:27035; number_of_response:1; }","duration":"234.003085ms","start":"2026-09-22T02:57:50.170413Z","end":"2026-09-22T02:57:50.404416Z","steps":["trace[1323830681] 'process raft request' (duration: 233.465704ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:50.78425Z","caller":"traceutil/trace.go:171","msg":"trace[1800580457] linearizableReadLoop","detail":"{readStateIndex:27937; appliedIndex:27936; }","duration":"124.04307ms","start":"2026-09-22T02:57:50.660176Z","end":"2026-09-22T02:57:50.784219Z","steps":["trace[1800580457] 'read index received' (duration: 123.736563ms)","trace[1800580457] 'applied index is now lower than readState.Index' (duration: 305.467µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:50.784355Z","caller":"traceutil/trace.go:171","msg":"trace[561208264] transaction","detail":"{read_only:false; response_revision:27036; number_of_response:1; }","duration":"135.783493ms","start":"2026-09-22T02:57:50.648525Z","end":"2026-09-22T02:57:50.784308Z","steps":["trace[561208264] 'process raft request' (duration: 135.501257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:50.784565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.358287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-45dbn.18d7847c6a4758b7\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-09-22T02:57:50.784677Z","caller":"traceutil/trace.go:171","msg":"trace[192755574] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-45dbn.18d7847c6a4758b7; range_end:; response_count:1; response_revision:27036; }","duration":"124.523981ms","start":"2026-09-22T02:57:50.660129Z","end":"2026-09-22T02:57:50.784653Z","steps":["trace[192755574] 'agreement among raft nodes before linearized reading' (duration: 124.216964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:51.068567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.270751ms","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-09-22T02:57:51.068702Z","caller":"traceutil/trace.go:171","msg":"trace[178959490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27037; }","duration":"178.432005ms","start":"2026-09-22T02:57:50.890224Z","end":"2026-09-22T02:57:51.068656Z","steps":["trace[178959490] 'range keys from in-memory index tree' (duration: 178.083988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:51.068748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.036207ms","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-09-22T02:57:51.068833Z","caller":"traceutil/trace.go:171","msg":"trace[440442830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27037; }","duration":"178.151199ms","start":"2026-09-22T02:57:50.890662Z","end":"2026-09-22T02:57:51.068813Z","steps":["trace[440442830] 'range keys from in-memory index tree' (duration: 177.874363ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:51.069194Z","caller":"traceutil/trace.go:171","msg":"trace[1356021612] transaction","detail":"{read_only:false; response_revision:27038; number_of_response:1; }","duration":"177.470983ms","start":"2026-09-22T02:57:50.891705Z","end":"2026-09-22T02:57:51.069176Z","steps":["trace[1356021612] 'process raft request' (duration: 128.908165ms)","trace[1356021612] 'compare' (duration: 48.313823ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:57:51.280408Z","caller":"traceutil/trace.go:171","msg":"trace[1193638390] transaction","detail":"{read_only:false; response_revision:27039; number_of_response:1; }","duration":"387.486451ms","start":"2026-09-22T02:57:50.892897Z","end":"2026-09-22T02:57:51.280383Z","steps":["trace[1193638390] 'process raft request' (duration: 368.808187ms)","trace[1193638390] 'compare' (duration: 18.48653ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:51.280541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:50.892867Z","time spent":"387.604054ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:51.2807Z","caller":"traceutil/trace.go:171","msg":"trace[1908250836] linearizableReadLoop","detail":"{readStateIndex:27941; appliedIndex:27938; }","duration":"342.09144ms","start":"2026-09-22T02:57:50.938589Z","end":"2026-09-22T02:57:51.280681Z","steps":["trace[1908250836] 'read index received' (duration: 82.064602ms)","trace[1908250836] 'applied index is now lower than readState.Index' (duration: 260.026098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:57:51.280832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.229794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:57:51.28086Z","caller":"traceutil/trace.go:171","msg":"trace[1206694895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27040; }","duration":"342.300945ms","start":"2026-09-22T02:57:50.938553Z","end":"2026-09-22T02:57:51.280854Z","steps":["trace[1206694895] 'agreement among raft nodes before linearized reading' (duration: 342.199743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:51.280882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:50.938539Z","time spent":"342.337336ms","remote":"127.0.0.1:48212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T02:57:51.280951Z","caller":"traceutil/trace.go:171","msg":"trace[2141763005] transaction","detail":"{read_only:false; response_revision:27040; number_of_response:1; }","duration":"385.728874ms","start":"2026-09-22T02:57:50.895202Z","end":"2026-09-22T02:57:51.280931Z","steps":["trace[2141763005] 'process raft request' (duration: 385.121551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:57:51.281015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:50.895184Z","time spent":"385.797025ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:57:51.28106Z","caller":"traceutil/trace.go:171","msg":"trace[509280291] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"207.495472ms","start":"2026-09-22T02:57:51.073556Z","end":"2026-09-22T02:57:51.281052Z","steps":["trace[509280291] 'process raft request' (duration: 207.046262ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:57:51.281301Z","caller":"traceutil/trace.go:171","msg":"trace[779804279] transaction","detail":"{read_only:false; response_revision:27042; number_of_response:1; }","duration":"206.623373ms","start":"2026-09-22T02:57:51.074671Z","end":"2026-09-22T02:57:51.281294Z","steps":["trace[779804279] 'process raft request' (duration: 206.347618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.292911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.038577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:00.293018Z","caller":"traceutil/trace.go:171","msg":"trace[1172179024] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:27107; }","duration":"353.222592ms","start":"2026-09-22T02:57:59.939772Z","end":"2026-09-22T02:58:00.292995Z","steps":["trace[1172179024] 'count revisions from in-memory index tree' (duration: 352.911934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.293079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:59.939743Z","time spent":"353.317473ms","remote":"127.0.0.1:49402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:58:00.293099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.082818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:00.293144Z","caller":"traceutil/trace.go:171","msg":"trace[1848361760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27107; }","duration":"353.172009ms","start":"2026-09-22T02:57:59.939958Z","end":"2026-09-22T02:58:00.29313Z","steps":["trace[1848361760] 'range keys from in-memory index tree' (duration: 352.980216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.293172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:59.93993Z","time spent":"353.234921ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T02:58:00.294015Z","caller":"traceutil/trace.go:171","msg":"trace[1221687876] linearizableReadLoop","detail":"{readStateIndex:28011; appliedIndex:28010; }","duration":"287.506101ms","start":"2026-09-22T02:58:00.006484Z","end":"2026-09-22T02:58:00.29399Z","steps":["trace[1221687876] 'read index received' (duration: 234.345362ms)","trace[1221687876] 'applied index is now lower than readState.Index' (duration: 53.159519ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:00.294054Z","caller":"traceutil/trace.go:171","msg":"trace[509010209] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"341.533188ms","start":"2026-09-22T02:57:59.952505Z","end":"2026-09-22T02:58:00.294038Z","steps":["trace[509010209] 'process raft request' (duration: 288.281637ms)","trace[509010209] 'compare' (duration: 52.838502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:00.294157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:59.952479Z","time spent":"341.61219ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:00.294199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.991736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:58:00.294274Z","caller":"traceutil/trace.go:171","msg":"trace[744734294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27108; }","duration":"252.081285ms","start":"2026-09-22T02:58:00.042155Z","end":"2026-09-22T02:58:00.294236Z","steps":["trace[744734294] 'agreement among raft nodes before linearized reading' (duration: 251.992724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.294272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.789557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-22T02:58:00.294346Z","caller":"traceutil/trace.go:171","msg":"trace[658565654] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27108; }","duration":"287.90412ms","start":"2026-09-22T02:58:00.006428Z","end":"2026-09-22T02:58:00.294332Z","steps":["trace[658565654] 'agreement among raft nodes before linearized reading' (duration: 287.681975ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:00.530649Z","caller":"traceutil/trace.go:171","msg":"trace[473664813] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"112.745866ms","start":"2026-09-22T02:58:00.417876Z","end":"2026-09-22T02:58:00.530622Z","steps":["trace[473664813] 'process raft request' (duration: 99.847288ms)","trace[473664813] 'compare' (duration: 12.675513ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:00.846226Z","caller":"traceutil/trace.go:171","msg":"trace[1336626541] linearizableReadLoop","detail":"{readStateIndex:28015; appliedIndex:28011; }","duration":"417.409057ms","start":"2026-09-22T02:58:00.428792Z","end":"2026-09-22T02:58:00.846202Z","steps":["trace[1336626541] 'read index received' (duration: 88.835138ms)","trace[1336626541] 'applied index is now lower than readState.Index' (duration: 328.572729ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:00.846366Z","caller":"traceutil/trace.go:171","msg":"trace[271582769] transaction","detail":"{read_only:false; response_revision:27111; number_of_response:1; }","duration":"425.903431ms","start":"2026-09-22T02:58:00.420452Z","end":"2026-09-22T02:58:00.846356Z","steps":["trace[271582769] 'process raft request' (duration: 425.608204ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:00.84636Z","caller":"traceutil/trace.go:171","msg":"trace[1756453805] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"427.774011ms","start":"2026-09-22T02:58:00.418541Z","end":"2026-09-22T02:58:00.846315Z","steps":["trace[1756453805] 'process raft request' (duration: 427.179059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.846483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:00.420424Z","time spent":"425.966452ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:00.846508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.701394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:58:00.846546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:00.418525Z","time spent":"427.920314ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:00.846574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.599204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:58:00.8466Z","caller":"traceutil/trace.go:171","msg":"trace[907144938] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"424.51212ms","start":"2026-09-22T02:58:00.422052Z","end":"2026-09-22T02:58:00.846564Z","steps":["trace[907144938] 'process raft request' (duration: 424.106732ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:00.846586Z","caller":"traceutil/trace.go:171","msg":"trace[1118050507] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27112; }","duration":"417.825077ms","start":"2026-09-22T02:58:00.428742Z","end":"2026-09-22T02:58:00.846567Z","steps":["trace[1118050507] 'agreement among raft nodes before linearized reading' (duration: 417.626602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.846682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.453401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:00.846718Z","caller":"traceutil/trace.go:171","msg":"trace[1590185952] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:27112; }","duration":"100.554692ms","start":"2026-09-22T02:58:00.746153Z","end":"2026-09-22T02:58:00.846708Z","steps":["trace[1590185952] 'agreement among raft nodes before linearized reading' (duration: 100.47426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.846724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:00.428729Z","time spent":"417.98538ms","remote":"127.0.0.1:48680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-22T02:58:00.846799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:00.422022Z","time spent":"424.683055ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:00.846647Z","caller":"traceutil/trace.go:171","msg":"trace[1654801331] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:27112; }","duration":"350.734117ms","start":"2026-09-22T02:58:00.495898Z","end":"2026-09-22T02:58:00.846632Z","steps":["trace[1654801331] 'agreement among raft nodes before linearized reading' (duration: 350.526633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:00.846895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:00.495873Z","time spent":"351.000473ms","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":19,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:58:01.254635Z","caller":"traceutil/trace.go:171","msg":"trace[1463247541] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"144.34684ms","start":"2026-09-22T02:58:01.110256Z","end":"2026-09-22T02:58:01.254602Z","steps":["trace[1463247541] 'process raft request' (duration: 144.111024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:01.895486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.043096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:01.895611Z","caller":"traceutil/trace.go:171","msg":"trace[1704900577] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"573.073942ms","start":"2026-09-22T02:58:01.322516Z","end":"2026-09-22T02:58:01.89559Z","steps":["trace[1704900577] 'process raft request' (duration: 269.447592ms)","trace[1704900577] 'compare' (duration: 302.044795ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:01.895701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.322491Z","time spent":"573.156002ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.211712Z","caller":"traceutil/trace.go:171","msg":"trace[386205615] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"888.059095ms","start":"2026-09-22T02:58:01.323599Z","end":"2026-09-22T02:58:02.211658Z","steps":["trace[386205615] 'process raft request' (duration: 850.226828ms)","trace[386205615] 'compare' (duration: 37.352547ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:02.211879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.323573Z","time spent":"888.215329ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.212295Z","caller":"traceutil/trace.go:171","msg":"trace[1514639314] linearizableReadLoop","detail":"{readStateIndex:28019; appliedIndex:28017; }","duration":"845.998038ms","start":"2026-09-22T02:58:01.366212Z","end":"2026-09-22T02:58:02.21221Z","steps":["trace[1514639314] 'read index received' (duration: 225.85007ms)","trace[1514639314] 'applied index is now lower than readState.Index' (duration: 620.143698ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:02.212366Z","caller":"traceutil/trace.go:171","msg":"trace[968876287] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"725.050514ms","start":"2026-09-22T02:58:01.487274Z","end":"2026-09-22T02:58:02.212324Z","steps":["trace[968876287] 'process raft request' (duration: 724.308337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.212526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.487247Z","time spent":"725.184566ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:02.212556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.347324ms","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-09-22T02:58:02.212596Z","caller":"traceutil/trace.go:171","msg":"trace[358837913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27117; }","duration":"846.428546ms","start":"2026-09-22T02:58:01.366157Z","end":"2026-09-22T02:58:02.212585Z","steps":["trace[358837913] 'agreement among raft nodes before linearized reading' (duration: 846.285454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.212633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.366135Z","time spent":"846.491437ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-22T02:58:02.213037Z","caller":"traceutil/trace.go:171","msg":"trace[1367099235] transaction","detail":"{read_only:false; response_revision:27118; number_of_response:1; }","duration":"616.0699ms","start":"2026-09-22T02:58:01.596954Z","end":"2026-09-22T02:58:02.213024Z","steps":["trace[1367099235] 'process raft request' (duration: 614.926245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.596921Z","time spent":"616.151952ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:02.213257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.724304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T02:58:02.213332Z","caller":"traceutil/trace.go:171","msg":"trace[18634023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27118; }","duration":"841.904188ms","start":"2026-09-22T02:58:01.371409Z","end":"2026-09-22T02:58:02.213313Z","steps":["trace[18634023] 'agreement among raft nodes before linearized reading' (duration: 841.638902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.652321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:02.213386Z","caller":"traceutil/trace.go:171","msg":"trace[1759914719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:27118; }","duration":"441.778305ms","start":"2026-09-22T02:58:01.771593Z","end":"2026-09-22T02:58:02.213371Z","steps":["trace[1759914719] 'agreement among raft nodes before linearized reading' (duration: 441.660902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.771567Z","time spent":"441.853145ms","remote":"127.0.0.1:49290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:58:02.213491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.354425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:02.21355Z","caller":"traceutil/trace.go:171","msg":"trace[1024772340] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:27118; }","duration":"653.467758ms","start":"2026-09-22T02:58:01.560065Z","end":"2026-09-22T02:58:02.213533Z","steps":["trace[1024772340] 'agreement among raft nodes before linearized reading' (duration: 653.343025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.371382Z","time spent":"841.987741ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-22T02:58:02.213565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.577939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:58:02.213616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.560036Z","time spent":"653.56666ms","remote":"127.0.0.1:51992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:58:02.213658Z","caller":"traceutil/trace.go:171","msg":"trace[1340130768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27118; }","duration":"272.745794ms","start":"2026-09-22T02:58:01.940886Z","end":"2026-09-22T02:58:02.213632Z","steps":["trace[1340130768] 'agreement among raft nodes before linearized reading' (duration: 272.540358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.752768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:58:02.213819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.74185ms","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-09-22T02:58:02.213884Z","caller":"traceutil/trace.go:171","msg":"trace[1044054146] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:27118; }","duration":"662.867471ms","start":"2026-09-22T02:58:01.550999Z","end":"2026-09-22T02:58:02.213866Z","steps":["trace[1044054146] 'agreement among raft nodes before linearized reading' (duration: 662.766859ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:02.213912Z","caller":"traceutil/trace.go:171","msg":"trace[879998038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27118; }","duration":"807.943204ms","start":"2026-09-22T02:58:01.40595Z","end":"2026-09-22T02:58:02.213893Z","steps":["trace[879998038] 'agreement among raft nodes before linearized reading' (duration: 807.596447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.213969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.405944Z","time spent":"808.016306ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T02:58:02.213986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.216989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:58:02.213919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.550971Z","time spent":"662.938332ms","remote":"127.0.0.1:48646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:58:02.214027Z","caller":"traceutil/trace.go:171","msg":"trace[36167683] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:27118; }","duration":"122.376854ms","start":"2026-09-22T02:58:02.091636Z","end":"2026-09-22T02:58:02.214013Z","steps":["trace[36167683] 'agreement among raft nodes before linearized reading' (duration: 122.272402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.21399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.084478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T02:58:02.214091Z","caller":"traceutil/trace.go:171","msg":"trace[1523449656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27118; }","duration":"808.242242ms","start":"2026-09-22T02:58:01.40583Z","end":"2026-09-22T02:58:02.214073Z","steps":["trace[1523449656] 'agreement among raft nodes before linearized reading' (duration: 807.928845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.214143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:01.405805Z","time spent":"808.329403ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T02:58:02.213814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.230563ms","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-09-22T02:58:02.214345Z","caller":"traceutil/trace.go:171","msg":"trace[62883201] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:27118; }","duration":"179.771825ms","start":"2026-09-22T02:58:02.034518Z","end":"2026-09-22T02:58:02.21429Z","steps":["trace[62883201] 'agreement among raft nodes before linearized reading' (duration: 178.885885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.635199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.823507ms","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-09-22T02:58:02.635388Z","caller":"traceutil/trace.go:171","msg":"trace[1622746658] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27118; }","duration":"262.024271ms","start":"2026-09-22T02:58:02.373331Z","end":"2026-09-22T02:58:02.635355Z","steps":["trace[1622746658] 'range keys from in-memory index tree' (duration: 261.797945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.635586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.094691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:02.635723Z","caller":"traceutil/trace.go:171","msg":"trace[578553928] transaction","detail":"{read_only:false; response_revision:27119; number_of_response:1; }","duration":"416.412687ms","start":"2026-09-22T02:58:02.219286Z","end":"2026-09-22T02:58:02.635699Z","steps":["trace[578553928] 'process raft request' (duration: 286.03722ms)","trace[578553928] 'compare' (duration: 129.957177ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:02.635862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:02.21926Z","time spent":"416.504228ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.641087Z","caller":"traceutil/trace.go:171","msg":"trace[1760596924] transaction","detail":"{read_only:false; response_revision:27120; number_of_response:1; }","duration":"421.102548ms","start":"2026-09-22T02:58:02.219966Z","end":"2026-09-22T02:58:02.641069Z","steps":["trace[1760596924] 'process raft request' (duration: 420.933744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.641211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:02.21994Z","time spent":"421.21316ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.641503Z","caller":"traceutil/trace.go:171","msg":"trace[907166309] transaction","detail":"{read_only:false; response_revision:27121; number_of_response:1; }","duration":"420.060434ms","start":"2026-09-22T02:58:02.221323Z","end":"2026-09-22T02:58:02.641384Z","steps":["trace[907166309] 'process raft request' (duration: 419.690586ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:02.641661Z","caller":"traceutil/trace.go:171","msg":"trace[647128265] transaction","detail":"{read_only:false; response_revision:27122; number_of_response:1; }","duration":"419.836279ms","start":"2026-09-22T02:58:02.221793Z","end":"2026-09-22T02:58:02.641629Z","steps":["trace[647128265] 'process raft request' (duration: 419.537064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.641775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:02.221305Z","time spent":"420.30442ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.641812Z","caller":"traceutil/trace.go:171","msg":"trace[1734149725] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"339.857123ms","start":"2026-09-22T02:58:02.301918Z","end":"2026-09-22T02:58:02.641775Z","steps":["trace[1734149725] 'process raft request' (duration: 339.655999ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:02.641885Z","caller":"traceutil/trace.go:171","msg":"trace[1536359099] transaction","detail":"{read_only:false; response_revision:27124; number_of_response:1; }","duration":"105.204872ms","start":"2026-09-22T02:58:02.536657Z","end":"2026-09-22T02:58:02.641862Z","steps":["trace[1536359099] 'process raft request' (duration: 105.117171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:02.641934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:02.221741Z","time spent":"420.021714ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:02.641967Z","caller":"traceutil/trace.go:171","msg":"trace[831265966] linearizableReadLoop","detail":"{readStateIndex:28026; appliedIndex:28021; }","duration":"262.216895ms","start":"2026-09-22T02:58:02.379727Z","end":"2026-09-22T02:58:02.641943Z","steps":["trace[831265966] 'read index received' (duration: 125.621933ms)","trace[831265966] 'applied index is now lower than readState.Index' (duration: 136.593171ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:02.641996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:02.301887Z","time spent":"340.001736ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:02.642093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.365068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:02.64217Z","caller":"traceutil/trace.go:171","msg":"trace[615862868] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:27124; }","duration":"262.512371ms","start":"2026-09-22T02:58:02.379644Z","end":"2026-09-22T02:58:02.642156Z","steps":["trace[615862868] 'agreement among raft nodes before linearized reading' (duration: 262.385549ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:10.467271Z","caller":"traceutil/trace.go:171","msg":"trace[278025251] transaction","detail":"{read_only:false; response_revision:27233; number_of_response:1; }","duration":"218.991111ms","start":"2026-09-22T02:58:10.248238Z","end":"2026-09-22T02:58:10.467229Z","steps":["trace[278025251] 'process raft request' (duration: 218.799957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.709251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.179397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:10.709458Z","caller":"traceutil/trace.go:171","msg":"trace[1730763108] transaction","detail":"{read_only:false; response_revision:27234; number_of_response:1; }","duration":"461.093061ms","start":"2026-09-22T02:58:10.248292Z","end":"2026-09-22T02:58:10.709385Z","steps":["trace[1730763108] 'process raft request' (duration: 340.65989ms)","trace[1730763108] 'compare' (duration: 119.92196ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:10.70951Z","caller":"traceutil/trace.go:171","msg":"trace[1050193290] linearizableReadLoop","detail":"{readStateIndex:28139; appliedIndex:28137; }","duration":"430.545061ms","start":"2026-09-22T02:58:10.278911Z","end":"2026-09-22T02:58:10.709457Z","steps":["trace[1050193290] 'read index received' (duration: 188.155724ms)","trace[1050193290] 'applied index is now lower than readState.Index' (duration: 242.381727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:10.709674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.248242Z","time spent":"461.316256ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:10.709707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.770177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:10.709788Z","caller":"traceutil/trace.go:171","msg":"trace[1843569076] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:27235; }","duration":"430.935349ms","start":"2026-09-22T02:58:10.278835Z","end":"2026-09-22T02:58:10.709771Z","steps":["trace[1843569076] 'agreement among raft nodes before linearized reading' (duration: 430.741845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.709828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.278801Z","time spent":"431.016532ms","remote":"127.0.0.1:53094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:58:10.807799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.670402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T02:58:10.807901Z","caller":"traceutil/trace.go:171","msg":"trace[1678199950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27236; }","duration":"111.799036ms","start":"2026-09-22T02:58:10.696074Z","end":"2026-09-22T02:58:10.807873Z","steps":["trace[1678199950] 'agreement among raft nodes before linearized reading' (duration: 111.472658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.807901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.450055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1feaffef-74ab-4a2a-94de-6c50886f9c29\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-09-22T02:58:10.807897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.319964ms","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-09-22T02:58:10.807897Z","caller":"traceutil/trace.go:171","msg":"trace[1437894524] transaction","detail":"{read_only:false; response_revision:27236; number_of_response:1; }","duration":"121.73935ms","start":"2026-09-22T02:58:10.686096Z","end":"2026-09-22T02:58:10.807836Z","steps":["trace[1437894524] 'process raft request' (duration: 121.254289ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:10.807971Z","caller":"traceutil/trace.go:171","msg":"trace[1040178680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1feaffef-74ab-4a2a-94de-6c50886f9c29; range_end:; response_count:1; response_revision:27236; }","duration":"477.562738ms","start":"2026-09-22T02:58:10.330386Z","end":"2026-09-22T02:58:10.807949Z","steps":["trace[1040178680] 'agreement among raft nodes before linearized reading' (duration: 477.340463ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:10.807976Z","caller":"traceutil/trace.go:171","msg":"trace[294810043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27236; }","duration":"117.425037ms","start":"2026-09-22T02:58:10.690533Z","end":"2026-09-22T02:58:10.807958Z","steps":["trace[294810043] 'agreement among raft nodes before linearized reading' (duration: 117.218962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.808006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.33036Z","time spent":"477.639609ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1feaffef-74ab-4a2a-94de-6c50886f9c29\" "} {"level":"warn","ts":"2026-09-22T02:58:10.807796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.369728ms","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-09-22T02:58:10.808058Z","caller":"traceutil/trace.go:171","msg":"trace[1079330298] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:27236; }","duration":"418.661045ms","start":"2026-09-22T02:58:10.389384Z","end":"2026-09-22T02:58:10.808045Z","steps":["trace[1079330298] 'agreement among raft nodes before linearized reading' (duration: 418.223546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.808096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.389348Z","time spent":"418.737727ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-09-22T02:58:10.808062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.561787ms","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-09-22T02:58:10.808189Z","caller":"traceutil/trace.go:171","msg":"trace[1471071077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27236; }","duration":"103.727272ms","start":"2026-09-22T02:58:10.704433Z","end":"2026-09-22T02:58:10.80816Z","steps":["trace[1471071077] 'agreement among raft nodes before linearized reading' (duration: 103.303632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.808183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.822693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-22T02:58:10.807917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.145411ms","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-09-22T02:58:10.808279Z","caller":"traceutil/trace.go:171","msg":"trace[937265582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27236; }","duration":"103.962935ms","start":"2026-09-22T02:58:10.704293Z","end":"2026-09-22T02:58:10.808256Z","steps":["trace[937265582] 'agreement among raft nodes before linearized reading' (duration: 103.566957ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:10.808326Z","caller":"traceutil/trace.go:171","msg":"trace[1191811387] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:27236; }","duration":"485.583851ms","start":"2026-09-22T02:58:10.322719Z","end":"2026-09-22T02:58:10.808303Z","steps":["trace[1191811387] 'agreement among raft nodes before linearized reading' (duration: 485.156892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.808361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.322685Z","time spent":"485.644733ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:58:10.807654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.527315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:10.808492Z","caller":"traceutil/trace.go:171","msg":"trace[1975786649] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:27236; }","duration":"527.436074ms","start":"2026-09-22T02:58:10.281044Z","end":"2026-09-22T02:58:10.808481Z","steps":["trace[1975786649] 'agreement among raft nodes before linearized reading' (duration: 526.471973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:10.808515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.28103Z","time spent":"527.476926ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:58:10.948752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.731005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-65392dc2-f67c-4503-a3db-b3e55e3db022\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-09-22T02:58:10.948889Z","caller":"traceutil/trace.go:171","msg":"trace[1282536101] range","detail":"{range_begin:/registry/persistentvolumes/pvc-65392dc2-f67c-4503-a3db-b3e55e3db022; range_end:; response_count:1; response_revision:27236; }","duration":"137.95189ms","start":"2026-09-22T02:58:10.810897Z","end":"2026-09-22T02:58:10.948849Z","steps":["trace[1282536101] 'range keys from in-memory index tree' (duration: 137.485331ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:10.97001Z","caller":"traceutil/trace.go:171","msg":"trace[796263348] transaction","detail":"{read_only:false; response_revision:27237; number_of_response:1; }","duration":"157.053523ms","start":"2026-09-22T02:58:10.812934Z","end":"2026-09-22T02:58:10.969988Z","steps":["trace[796263348] 'process raft request' (duration: 156.859589ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:11.208848Z","caller":"traceutil/trace.go:171","msg":"trace[1021664329] transaction","detail":"{read_only:false; response_revision:27238; number_of_response:1; }","duration":"395.005264ms","start":"2026-09-22T02:58:10.813791Z","end":"2026-09-22T02:58:11.208796Z","steps":["trace[1021664329] 'process raft request' (duration: 378.414746ms)","trace[1021664329] 'compare' (duration: 15.849762ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:11.208946Z","caller":"traceutil/trace.go:171","msg":"trace[230694434] transaction","detail":"{read_only:false; response_revision:27239; number_of_response:1; }","duration":"394.943981ms","start":"2026-09-22T02:58:10.813946Z","end":"2026-09-22T02:58:11.20889Z","steps":["trace[230694434] 'process raft request' (duration: 394.766188ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:11.20901Z","caller":"traceutil/trace.go:171","msg":"trace[699730276] linearizableReadLoop","detail":"{readStateIndex:28149; appliedIndex:28141; }","duration":"297.378785ms","start":"2026-09-22T02:58:10.911609Z","end":"2026-09-22T02:58:11.208988Z","steps":["trace[699730276] 'read index received' (duration: 58.253779ms)","trace[699730276] 'applied index is now lower than readState.Index' (duration: 239.123806ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:11.209088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.813765Z","time spent":"395.236678ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:11.209199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.813914Z","time spent":"395.138747ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:11.209248Z","caller":"traceutil/trace.go:171","msg":"trace[857391184] transaction","detail":"{read_only:false; response_revision:27240; number_of_response:1; }","duration":"394.316149ms","start":"2026-09-22T02:58:10.814926Z","end":"2026-09-22T02:58:11.209242Z","steps":["trace[857391184] 'process raft request' (duration: 393.851619ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:11.20928Z","caller":"traceutil/trace.go:171","msg":"trace[1116861568] transaction","detail":"{read_only:false; response_revision:27244; number_of_response:1; }","duration":"324.033741ms","start":"2026-09-22T02:58:10.885233Z","end":"2026-09-22T02:58:11.209267Z","steps":["trace[1116861568] 'process raft request' (duration: 323.713554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.209295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.814908Z","time spent":"394.35839ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:11.209394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.885213Z","time spent":"324.100271ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:11.209495Z","caller":"traceutil/trace.go:171","msg":"trace[1829992821] transaction","detail":"{read_only:false; response_revision:27242; number_of_response:1; }","duration":"349.643944ms","start":"2026-09-22T02:58:10.859825Z","end":"2026-09-22T02:58:11.209469Z","steps":["trace[1829992821] 'process raft request' (duration: 349.046211ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:11.209217Z","caller":"traceutil/trace.go:171","msg":"trace[2069115794] transaction","detail":"{read_only:false; response_revision:27243; number_of_response:1; }","duration":"325.198126ms","start":"2026-09-22T02:58:10.884011Z","end":"2026-09-22T02:58:11.209209Z","steps":["trace[2069115794] 'process raft request' (duration: 324.896949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.209797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.883977Z","time spent":"325.752667ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:11.209924Z","caller":"traceutil/trace.go:171","msg":"trace[1965174761] transaction","detail":"{read_only:false; response_revision:27241; number_of_response:1; }","duration":"394.693727ms","start":"2026-09-22T02:58:10.81522Z","end":"2026-09-22T02:58:11.209914Z","steps":["trace[1965174761] 'process raft request' (duration: 393.612263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.209666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.059809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-22T02:58:11.209977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.815206Z","time spent":"394.739389ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:58:11.210048Z","caller":"traceutil/trace.go:171","msg":"trace[743767342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27244; }","duration":"298.441437ms","start":"2026-09-22T02:58:10.911553Z","end":"2026-09-22T02:58:11.209994Z","steps":["trace[743767342] 'agreement among raft nodes before linearized reading' (duration: 298.010977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.21014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.077664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:11.21018Z","caller":"traceutil/trace.go:171","msg":"trace[1797352214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27244; }","duration":"269.170056ms","start":"2026-09-22T02:58:10.940999Z","end":"2026-09-22T02:58:11.210169Z","steps":["trace[1797352214] 'agreement among raft nodes before linearized reading' (duration: 269.111404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.210472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.770869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-22T02:58:11.210571Z","caller":"traceutil/trace.go:171","msg":"trace[998625053] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27244; }","duration":"201.928981ms","start":"2026-09-22T02:58:11.008616Z","end":"2026-09-22T02:58:11.210545Z","steps":["trace[998625053] 'agreement among raft nodes before linearized reading' (duration: 201.585714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:11.209673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:10.859803Z","time spent":"349.780876ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:11.452807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.810892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:11.452993Z","caller":"traceutil/trace.go:171","msg":"trace[417148350] linearizableReadLoop","detail":"{readStateIndex:28151; appliedIndex:28149; }","duration":"144.314548ms","start":"2026-09-22T02:58:11.308642Z","end":"2026-09-22T02:58:11.452957Z","steps":["trace[417148350] 'read index received' (duration: 34.27322ms)","trace[417148350] 'applied index is now lower than readState.Index' (duration: 110.040538ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:11.453088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.444581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:11.453112Z","caller":"traceutil/trace.go:171","msg":"trace[1993267016] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27246; }","duration":"144.495002ms","start":"2026-09-22T02:58:11.30861Z","end":"2026-09-22T02:58:11.453105Z","steps":["trace[1993267016] 'agreement among raft nodes before linearized reading' (duration: 144.40495ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:11.45318Z","caller":"traceutil/trace.go:171","msg":"trace[1047748388] transaction","detail":"{read_only:false; response_revision:27245; number_of_response:1; }","duration":"234.311572ms","start":"2026-09-22T02:58:11.218808Z","end":"2026-09-22T02:58:11.45312Z","steps":["trace[1047748388] 'process raft request' (duration: 124.10869ms)","trace[1047748388] 'compare' (duration: 109.696671ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:11.453245Z","caller":"traceutil/trace.go:171","msg":"trace[217366467] transaction","detail":"{read_only:false; response_revision:27246; number_of_response:1; }","duration":"151.870961ms","start":"2026-09-22T02:58:11.30132Z","end":"2026-09-22T02:58:11.453191Z","steps":["trace[217366467] 'process raft request' (duration: 151.597235ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:17.006874Z","caller":"traceutil/trace.go:171","msg":"trace[1047805631] transaction","detail":"{read_only:false; response_revision:27296; number_of_response:1; }","duration":"142.384356ms","start":"2026-09-22T02:58:16.864447Z","end":"2026-09-22T02:58:17.006832Z","steps":["trace[1047805631] 'process raft request' (duration: 114.865441ms)","trace[1047805631] 'compare' (duration: 27.29199ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:17.154608Z","caller":"traceutil/trace.go:171","msg":"trace[1781381062] transaction","detail":"{read_only:false; response_revision:27297; number_of_response:1; }","duration":"113.664185ms","start":"2026-09-22T02:58:17.040898Z","end":"2026-09-22T02:58:17.154562Z","steps":["trace[1781381062] 'process raft request' (duration: 111.617031ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:21.648418Z","caller":"traceutil/trace.go:171","msg":"trace[1594151731] transaction","detail":"{read_only:false; response_revision:27349; number_of_response:1; }","duration":"109.459784ms","start":"2026-09-22T02:58:21.538884Z","end":"2026-09-22T02:58:21.648344Z","steps":["trace[1594151731] 'process raft request' (duration: 74.11479ms)","trace[1594151731] 'compare' (duration: 35.044108ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:23.823999Z","caller":"traceutil/trace.go:171","msg":"trace[747926168] transaction","detail":"{read_only:false; response_revision:27364; number_of_response:1; }","duration":"155.428197ms","start":"2026-09-22T02:58:23.668532Z","end":"2026-09-22T02:58:23.82396Z","steps":["trace[747926168] 'process raft request' (duration: 155.09001ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:24.895238Z","caller":"traceutil/trace.go:171","msg":"trace[172365858] transaction","detail":"{read_only:false; response_revision:27371; number_of_response:1; }","duration":"125.653985ms","start":"2026-09-22T02:58:24.769548Z","end":"2026-09-22T02:58:24.895202Z","steps":["trace[172365858] 'process raft request' (duration: 121.320721ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.304207Z","caller":"traceutil/trace.go:171","msg":"trace[295269679] transaction","detail":"{read_only:false; response_revision:27414; number_of_response:1; }","duration":"125.751607ms","start":"2026-09-22T02:58:31.178419Z","end":"2026-09-22T02:58:31.30417Z","steps":["trace[295269679] 'process raft request' (duration: 125.513912ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.464867Z","caller":"traceutil/trace.go:171","msg":"trace[431160788] linearizableReadLoop","detail":"{readStateIndex:28325; appliedIndex:28324; }","duration":"155.01622ms","start":"2026-09-22T02:58:31.309808Z","end":"2026-09-22T02:58:31.464824Z","steps":["trace[431160788] 'read index received' (duration: 148.869166ms)","trace[431160788] 'applied index is now lower than readState.Index' (duration: 6.141773ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:31.464926Z","caller":"traceutil/trace.go:171","msg":"trace[1902811126] transaction","detail":"{read_only:false; response_revision:27415; number_of_response:1; }","duration":"218.01435ms","start":"2026-09-22T02:58:31.246869Z","end":"2026-09-22T02:58:31.464883Z","steps":["trace[1902811126] 'process raft request' (duration: 211.54972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:31.465213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.371187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T02:58:31.465257Z","caller":"traceutil/trace.go:171","msg":"trace[2011074991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27415; }","duration":"155.51724ms","start":"2026-09-22T02:58:31.309727Z","end":"2026-09-22T02:58:31.465245Z","steps":["trace[2011074991] 'agreement among raft nodes before linearized reading' (duration: 155.279314ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.61128Z","caller":"traceutil/trace.go:171","msg":"trace[1818314695] transaction","detail":"{read_only:false; response_revision:27416; number_of_response:1; }","duration":"255.355506ms","start":"2026-09-22T02:58:31.355891Z","end":"2026-09-22T02:58:31.611247Z","steps":["trace[1818314695] 'process raft request' (duration: 255.118791ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.611597Z","caller":"traceutil/trace.go:171","msg":"trace[721993199] linearizableReadLoop","detail":"{readStateIndex:28327; appliedIndex:28326; }","duration":"146.589537ms","start":"2026-09-22T02:58:31.46497Z","end":"2026-09-22T02:58:31.611559Z","steps":["trace[721993199] 'read index received' (duration: 146.28637ms)","trace[721993199] 'applied index is now lower than readState.Index' (duration: 300.107µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:31.611634Z","caller":"traceutil/trace.go:171","msg":"trace[555915452] transaction","detail":"{read_only:false; response_revision:27417; number_of_response:1; }","duration":"253.390064ms","start":"2026-09-22T02:58:31.358209Z","end":"2026-09-22T02:58:31.611599Z","steps":["trace[555915452] 'process raft request' (duration: 252.980046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:31.61183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.238903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T02:58:31.611886Z","caller":"traceutil/trace.go:171","msg":"trace[775471205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27417; }","duration":"242.366036ms","start":"2026-09-22T02:58:31.369502Z","end":"2026-09-22T02:58:31.611868Z","steps":["trace[775471205] 'agreement among raft nodes before linearized reading' (duration: 242.08447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:31.611964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.853779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-22T02:58:31.611965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.966529ms","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-09-22T02:58:31.612018Z","caller":"traceutil/trace.go:171","msg":"trace[2061513754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27417; }","duration":"243.978031ms","start":"2026-09-22T02:58:31.368023Z","end":"2026-09-22T02:58:31.612001Z","steps":["trace[2061513754] 'agreement among raft nodes before linearized reading' (duration: 243.704495ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.612036Z","caller":"traceutil/trace.go:171","msg":"trace[50976184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27417; }","duration":"243.087201ms","start":"2026-09-22T02:58:31.368931Z","end":"2026-09-22T02:58:31.612018Z","steps":["trace[50976184] 'agreement among raft nodes before linearized reading' (duration: 242.690183ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.625582Z","caller":"traceutil/trace.go:171","msg":"trace[1791596752] transaction","detail":"{read_only:false; response_revision:27418; number_of_response:1; }","duration":"154.677562ms","start":"2026-09-22T02:58:31.47088Z","end":"2026-09-22T02:58:31.625558Z","steps":["trace[1791596752] 'process raft request' (duration: 154.502978ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.77148Z","caller":"traceutil/trace.go:171","msg":"trace[1423529541] transaction","detail":"{read_only:false; response_revision:27420; number_of_response:1; }","duration":"154.16367ms","start":"2026-09-22T02:58:31.617273Z","end":"2026-09-22T02:58:31.771437Z","steps":["trace[1423529541] 'process raft request' (duration: 133.051473ms)","trace[1423529541] 'compare' (duration: 20.693858ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:31.771626Z","caller":"traceutil/trace.go:171","msg":"trace[692743544] transaction","detail":"{read_only:false; response_revision:27422; number_of_response:1; }","duration":"152.400942ms","start":"2026-09-22T02:58:31.619163Z","end":"2026-09-22T02:58:31.771564Z","steps":["trace[692743544] 'process raft request' (duration: 152.076216ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:31.771661Z","caller":"traceutil/trace.go:171","msg":"trace[1376085767] transaction","detail":"{read_only:false; response_revision:27421; number_of_response:1; }","duration":"154.321783ms","start":"2026-09-22T02:58:31.61728Z","end":"2026-09-22T02:58:31.771602Z","steps":["trace[1376085767] 'process raft request' (duration: 153.905034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:34.82061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.592412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:58:34.82072Z","caller":"traceutil/trace.go:171","msg":"trace[73255337] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:27442; }","duration":"118.780586ms","start":"2026-09-22T02:58:34.701916Z","end":"2026-09-22T02:58:34.820696Z","steps":["trace[73255337] 'count revisions from in-memory index tree' (duration: 118.451619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:34.820833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.689921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:34.820928Z","caller":"traceutil/trace.go:171","msg":"trace[1165084276] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:27442; }","duration":"116.827213ms","start":"2026-09-22T02:58:34.704079Z","end":"2026-09-22T02:58:34.820907Z","steps":["trace[1165084276] 'count revisions from in-memory index tree' (duration: 116.601279ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:36.650333Z","caller":"traceutil/trace.go:171","msg":"trace[454545452] linearizableReadLoop","detail":"{readStateIndex:28374; appliedIndex:28373; }","duration":"122.68098ms","start":"2026-09-22T02:58:36.527623Z","end":"2026-09-22T02:58:36.650304Z","steps":["trace[454545452] 'read index received' (duration: 45.443751ms)","trace[454545452] 'applied index is now lower than readState.Index' (duration: 77.235509ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:36.650666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.009198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-09-22T02:58:36.650729Z","caller":"traceutil/trace.go:171","msg":"trace[1238602256] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:27462; }","duration":"123.11765ms","start":"2026-09-22T02:58:36.527594Z","end":"2026-09-22T02:58:36.650712Z","steps":["trace[1238602256] 'agreement among raft nodes before linearized reading' (duration: 122.862114ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:36.855342Z","caller":"traceutil/trace.go:171","msg":"trace[782049032] linearizableReadLoop","detail":"{readStateIndex:28377; appliedIndex:28376; }","duration":"122.232881ms","start":"2026-09-22T02:58:36.733078Z","end":"2026-09-22T02:58:36.855311Z","steps":["trace[782049032] 'read index received' (duration: 121.958856ms)","trace[782049032] 'applied index is now lower than readState.Index' (duration: 272.565µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:36.855395Z","caller":"traceutil/trace.go:171","msg":"trace[1942060852] transaction","detail":"{read_only:false; response_revision:27465; number_of_response:1; }","duration":"128.101737ms","start":"2026-09-22T02:58:36.727263Z","end":"2026-09-22T02:58:36.855365Z","steps":["trace[1942060852] 'process raft request' (duration: 127.781041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:36.85566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.560058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-09-22T02:58:36.855756Z","caller":"traceutil/trace.go:171","msg":"trace[1403941017] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:27465; }","duration":"122.703192ms","start":"2026-09-22T02:58:36.733029Z","end":"2026-09-22T02:58:36.855732Z","steps":["trace[1403941017] 'agreement among raft nodes before linearized reading' (duration: 122.392035ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:41.949209Z","caller":"traceutil/trace.go:171","msg":"trace[1653473832] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"106.00492ms","start":"2026-09-22T02:58:41.843168Z","end":"2026-09-22T02:58:41.949173Z","steps":["trace[1653473832] 'process raft request' (duration: 105.797506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:42.366251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.965474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:42.366603Z","caller":"traceutil/trace.go:171","msg":"trace[1023400721] transaction","detail":"{read_only:false; response_revision:27513; number_of_response:1; }","duration":"298.040359ms","start":"2026-09-22T02:58:42.068536Z","end":"2026-09-22T02:58:42.366576Z","steps":["trace[1023400721] 'process raft request' (duration: 297.978207ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:42.366636Z","caller":"traceutil/trace.go:171","msg":"trace[726248550] transaction","detail":"{read_only:false; response_revision:27511; number_of_response:1; }","duration":"523.103221ms","start":"2026-09-22T02:58:41.843423Z","end":"2026-09-22T02:58:42.366527Z","steps":["trace[726248550] 'process raft request' (duration: 245.723089ms)","trace[726248550] 'compare' (duration: 276.33813ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:42.366802Z","caller":"traceutil/trace.go:171","msg":"trace[1556432795] transaction","detail":"{read_only:false; response_revision:27512; number_of_response:1; }","duration":"522.862126ms","start":"2026-09-22T02:58:41.843907Z","end":"2026-09-22T02:58:42.366769Z","steps":["trace[1556432795] 'process raft request' (duration: 522.471948ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:42.366829Z","caller":"traceutil/trace.go:171","msg":"trace[1340098798] linearizableReadLoop","detail":"{readStateIndex:28425; appliedIndex:28422; }","duration":"452.355353ms","start":"2026-09-22T02:58:41.914435Z","end":"2026-09-22T02:58:42.36679Z","steps":["trace[1340098798] 'read index received' (duration: 34.485365ms)","trace[1340098798] 'applied index is now lower than readState.Index' (duration: 417.847747ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:42.366865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:41.843887Z","time spent":"522.937777ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:42.366894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:41.843401Z","time spent":"523.333197ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:58:42.367052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.608718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-22T02:58:42.367073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.390571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:58:42.367134Z","caller":"traceutil/trace.go:171","msg":"trace[82098844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27513; }","duration":"452.753652ms","start":"2026-09-22T02:58:41.914358Z","end":"2026-09-22T02:58:42.367111Z","steps":["trace[82098844] 'agreement among raft nodes before linearized reading' (duration: 452.552298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:42.367152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.598495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-22T02:58:42.36717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:41.91433Z","time spent":"452.833803ms","remote":"127.0.0.1:48508","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":"info","ts":"2026-09-22T02:58:42.367182Z","caller":"traceutil/trace.go:171","msg":"trace[721897974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27513; }","duration":"426.554095ms","start":"2026-09-22T02:58:41.9406Z","end":"2026-09-22T02:58:42.367154Z","steps":["trace[721897974] 'agreement among raft nodes before linearized reading' (duration: 426.37517ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:42.367195Z","caller":"traceutil/trace.go:171","msg":"trace[697557528] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27513; }","duration":"269.701398ms","start":"2026-09-22T02:58:42.09748Z","end":"2026-09-22T02:58:42.367182Z","steps":["trace[697557528] 'agreement among raft nodes before linearized reading' (duration: 269.506423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:42.367232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:58:41.940578Z","time spent":"426.645377ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:58:42.641263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.419639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:42.641394Z","caller":"traceutil/trace.go:171","msg":"trace[1091130574] linearizableReadLoop","detail":"{readStateIndex:28427; appliedIndex:28426; }","duration":"267.894027ms","start":"2026-09-22T02:58:42.373481Z","end":"2026-09-22T02:58:42.641375Z","steps":["trace[1091130574] 'read index received' (duration: 136.218802ms)","trace[1091130574] 'applied index is now lower than readState.Index' (duration: 131.673685ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:42.641502Z","caller":"traceutil/trace.go:171","msg":"trace[2040084234] transaction","detail":"{read_only:false; response_revision:27514; number_of_response:1; }","duration":"268.254484ms","start":"2026-09-22T02:58:42.373198Z","end":"2026-09-22T02:58:42.641452Z","steps":["trace[2040084234] 'process raft request' (duration: 136.54762ms)","trace[2040084234] 'compare' (duration: 131.115302ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:58:42.6416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.163283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-09-22T02:58:42.641637Z","caller":"traceutil/trace.go:171","msg":"trace[846789473] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27514; }","duration":"268.254305ms","start":"2026-09-22T02:58:42.373371Z","end":"2026-09-22T02:58:42.641626Z","steps":["trace[846789473] 'agreement among raft nodes before linearized reading' (duration: 268.052141ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:42.834793Z","caller":"traceutil/trace.go:171","msg":"trace[954361059] transaction","detail":"{read_only:false; response_revision:27515; number_of_response:1; }","duration":"148.087069ms","start":"2026-09-22T02:58:42.686635Z","end":"2026-09-22T02:58:42.834722Z","steps":["trace[954361059] 'process raft request' (duration: 99.269924ms)","trace[954361059] 'compare' (duration: 48.56028ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:43.007539Z","caller":"traceutil/trace.go:171","msg":"trace[159272885] transaction","detail":"{read_only:false; response_revision:27516; number_of_response:1; }","duration":"150.080051ms","start":"2026-09-22T02:58:42.857417Z","end":"2026-09-22T02:58:43.007497Z","steps":["trace[159272885] 'process raft request' (duration: 149.798576ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:43.027801Z","caller":"traceutil/trace.go:171","msg":"trace[106008772] transaction","detail":"{read_only:false; response_revision:27517; number_of_response:1; }","duration":"126.086055ms","start":"2026-09-22T02:58:42.901666Z","end":"2026-09-22T02:58:43.027752Z","steps":["trace[106008772] 'process raft request' (duration: 125.824729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:48.012204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.16411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-22T02:58:48.012304Z","caller":"traceutil/trace.go:171","msg":"trace[816735187] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27551; }","duration":"142.313314ms","start":"2026-09-22T02:58:47.869968Z","end":"2026-09-22T02:58:48.012281Z","steps":["trace[816735187] 'range keys from in-memory index tree' (duration: 141.955517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:49.334425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.095222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:58:49.334735Z","caller":"traceutil/trace.go:171","msg":"trace[1046399398] transaction","detail":"{read_only:false; response_revision:27561; number_of_response:1; }","duration":"156.757546ms","start":"2026-09-22T02:58:49.177937Z","end":"2026-09-22T02:58:49.334695Z","steps":["trace[1046399398] 'process raft request' (duration: 156.607784ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:49.334711Z","caller":"traceutil/trace.go:171","msg":"trace[729670516] transaction","detail":"{read_only:false; response_revision:27560; number_of_response:1; }","duration":"212.545652ms","start":"2026-09-22T02:58:49.122101Z","end":"2026-09-22T02:58:49.334647Z","steps":["trace[729670516] 'process raft request' (duration: 81.118313ms)","trace[729670516] 'compare' (duration: 130.11069ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:50.503496Z","caller":"traceutil/trace.go:171","msg":"trace[1606572478] transaction","detail":"{read_only:false; response_revision:27570; number_of_response:1; }","duration":"103.186059ms","start":"2026-09-22T02:58:50.400285Z","end":"2026-09-22T02:58:50.503471Z","steps":["trace[1606572478] 'process raft request' (duration: 102.831191ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:50.718135Z","caller":"traceutil/trace.go:171","msg":"trace[15767211] transaction","detail":"{read_only:false; response_revision:27571; number_of_response:1; }","duration":"209.566048ms","start":"2026-09-22T02:58:50.508528Z","end":"2026-09-22T02:58:50.718094Z","steps":["trace[15767211] 'process raft request' (duration: 152.563366ms)","trace[15767211] 'compare' (duration: 56.583103ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:50.802634Z","caller":"traceutil/trace.go:171","msg":"trace[543920611] linearizableReadLoop","detail":"{readStateIndex:28487; appliedIndex:28486; }","duration":"115.905994ms","start":"2026-09-22T02:58:50.686694Z","end":"2026-09-22T02:58:50.8026Z","steps":["trace[543920611] 'read index received' (duration: 115.639518ms)","trace[543920611] 'applied index is now lower than readState.Index' (duration: 265.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:50.803067Z","caller":"traceutil/trace.go:171","msg":"trace[394695547] transaction","detail":"{read_only:false; response_revision:27572; number_of_response:1; }","duration":"294.3705ms","start":"2026-09-22T02:58:50.508682Z","end":"2026-09-22T02:58:50.803052Z","steps":["trace[394695547] 'process raft request' (duration: 293.741886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:58:50.804549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845396ms","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-09-22T02:58:50.804634Z","caller":"traceutil/trace.go:171","msg":"trace[1254932229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27572; }","duration":"117.996029ms","start":"2026-09-22T02:58:50.686618Z","end":"2026-09-22T02:58:50.804614Z","steps":["trace[1254932229] 'agreement among raft nodes before linearized reading' (duration: 117.837946ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:50.971399Z","caller":"traceutil/trace.go:171","msg":"trace[171756043] transaction","detail":"{read_only:false; response_revision:27573; number_of_response:1; }","duration":"153.812903ms","start":"2026-09-22T02:58:50.81756Z","end":"2026-09-22T02:58:50.971373Z","steps":["trace[171756043] 'process raft request' (duration: 148.887046ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:50.971907Z","caller":"traceutil/trace.go:171","msg":"trace[445557380] transaction","detail":"{read_only:false; response_revision:27574; number_of_response:1; }","duration":"105.921908ms","start":"2026-09-22T02:58:50.865899Z","end":"2026-09-22T02:58:50.971821Z","steps":["trace[445557380] 'process raft request' (duration: 105.247464ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:58:51.223293Z","caller":"traceutil/trace.go:171","msg":"trace[509258643] transaction","detail":"{read_only:false; response_revision:27575; number_of_response:1; }","duration":"153.281341ms","start":"2026-09-22T02:58:51.069968Z","end":"2026-09-22T02:58:51.223249Z","steps":["trace[509258643] 'process raft request' (duration: 55.928107ms)","trace[509258643] 'compare' (duration: 96.662159ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:58:55.484017Z","caller":"traceutil/trace.go:171","msg":"trace[1269371768] transaction","detail":"{read_only:false; response_revision:27607; number_of_response:1; }","duration":"114.219198ms","start":"2026-09-22T02:58:55.369764Z","end":"2026-09-22T02:58:55.483983Z","steps":["trace[1269371768] 'process raft request' (duration: 114.008454ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:01.385417Z","caller":"traceutil/trace.go:171","msg":"trace[321227702] transaction","detail":"{read_only:false; response_revision:27648; number_of_response:1; }","duration":"125.508761ms","start":"2026-09-22T02:59:01.259867Z","end":"2026-09-22T02:59:01.385376Z","steps":["trace[321227702] 'process raft request' (duration: 125.239025ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:01.643218Z","caller":"traceutil/trace.go:171","msg":"trace[690006619] transaction","detail":"{read_only:false; response_revision:27649; number_of_response:1; }","duration":"129.780474ms","start":"2026-09-22T02:59:01.513411Z","end":"2026-09-22T02:59:01.643191Z","steps":["trace[690006619] 'process raft request' (duration: 129.536228ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:01.644322Z","caller":"traceutil/trace.go:171","msg":"trace[1163560477] transaction","detail":"{read_only:false; response_revision:27650; number_of_response:1; }","duration":"127.687738ms","start":"2026-09-22T02:59:01.516588Z","end":"2026-09-22T02:59:01.644276Z","steps":["trace[1163560477] 'process raft request' (duration: 127.384842ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:01.987651Z","caller":"traceutil/trace.go:171","msg":"trace[1393974207] linearizableReadLoop","detail":"{readStateIndex:28571; appliedIndex:28568; }","duration":"115.136948ms","start":"2026-09-22T02:59:01.87249Z","end":"2026-09-22T02:59:01.987627Z","steps":["trace[1393974207] 'read index received' (duration: 35.976748ms)","trace[1393974207] 'applied index is now lower than readState.Index' (duration: 79.15909ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:59:01.987744Z","caller":"traceutil/trace.go:171","msg":"trace[1315413779] transaction","detail":"{read_only:false; response_revision:27654; number_of_response:1; }","duration":"127.504134ms","start":"2026-09-22T02:59:01.860197Z","end":"2026-09-22T02:59:01.987701Z","steps":["trace[1315413779] 'process raft request' (duration: 127.3151ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:01.987754Z","caller":"traceutil/trace.go:171","msg":"trace[756631312] transaction","detail":"{read_only:false; response_revision:27653; number_of_response:1; }","duration":"127.556116ms","start":"2026-09-22T02:59:01.860134Z","end":"2026-09-22T02:59:01.98769Z","steps":["trace[756631312] 'process raft request' (duration: 126.971013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:59:01.98789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.378123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T02:59:01.987939Z","caller":"traceutil/trace.go:171","msg":"trace[1960415297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27654; }","duration":"115.478205ms","start":"2026-09-22T02:59:01.872446Z","end":"2026-09-22T02:59:01.987924Z","steps":["trace[1960415297] 'agreement among raft nodes before linearized reading' (duration: 115.292251ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:59:03.180887Z","caller":"traceutil/trace.go:171","msg":"trace[1896349900] transaction","detail":"{read_only:false; response_revision:27662; number_of_response:1; }","duration":"109.198599ms","start":"2026-09-22T02:59:03.071618Z","end":"2026-09-22T02:59:03.180817Z","steps":["trace[1896349900] 'process raft request' (duration: 69.936792ms)","trace[1896349900] 'compare' (duration: 39.040362ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:00:01.30352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.255783ms","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-09-22T03:00:01.30361Z","caller":"traceutil/trace.go:171","msg":"trace[1290428162] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:28308; }","duration":"105.397146ms","start":"2026-09-22T03:00:01.198194Z","end":"2026-09-22T03:00:01.303591Z","steps":["trace[1290428162] 'agreement among raft nodes before linearized reading' (duration: 105.235693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:00:01.304149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.116861ms","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-09-22T03:00:01.304226Z","caller":"traceutil/trace.go:171","msg":"trace[660782248] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:28308; }","duration":"105.260763ms","start":"2026-09-22T03:00:01.19895Z","end":"2026-09-22T03:00:01.304211Z","steps":["trace[660782248] 'agreement among raft nodes before linearized reading' (duration: 105.139541ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:01:17.979811Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T03:01:17.98654Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-22T03:01:17.986704Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-22T03:02:39.965451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26962} {"level":"info","ts":"2026-09-22T03:02:40.133788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26962,"took":"161.5223ms","hash":3481533701,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":35753984,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-22T03:02:40.133882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3481533701,"revision":26962,"compact-revision":23589} {"level":"info","ts":"2026-09-22T03:03:13.199567Z","caller":"traceutil/trace.go:171","msg":"trace[1195334536] transaction","detail":"{read_only:false; response_revision:30217; number_of_response:1; }","duration":"136.706263ms","start":"2026-09-22T03:03:13.062809Z","end":"2026-09-22T03:03:13.199515Z","steps":["trace[1195334536] 'process raft request' (duration: 74.458778ms)","trace[1195334536] 'compare' (duration: 61.713373ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:07:39.972167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29866} {"level":"info","ts":"2026-09-22T03:07:40.125778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29866,"took":"148.989558ms","hash":484067321,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":33021952,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-22T03:07:40.125864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":484067321,"revision":29866,"compact-revision":26962} {"level":"info","ts":"2026-09-22T03:12:39.98734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32292} {"level":"info","ts":"2026-09-22T03:12:40.158908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32292,"took":"165.487106ms","hash":1750438248,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T03:12:40.159017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750438248,"revision":32292,"compact-revision":29866} {"level":"info","ts":"2026-09-22T03:17:39.997921Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34520} {"level":"info","ts":"2026-09-22T03:17:40.159535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34520,"took":"157.337379ms","hash":2739313508,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T03:17:40.159614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2739313508,"revision":34520,"compact-revision":32292} {"level":"info","ts":"2026-09-22T03:21:58.076129Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T03:21:58.085152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-22T03:21:58.085286Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-22T03:22:40.017241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36749} {"level":"info","ts":"2026-09-22T03:22:40.166291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36749,"took":"142.755762ms","hash":3104456355,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T03:22:40.166375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3104456355,"revision":36749,"compact-revision":34520} {"level":"info","ts":"2026-09-22T03:27:40.026228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39089} {"level":"info","ts":"2026-09-22T03:27:40.17806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39089,"took":"146.972232ms","hash":4063853115,"current-db-size-bytes":59342848,"current-db-size":"59 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T03:27:40.178157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4063853115,"revision":39089,"compact-revision":36749} {"level":"info","ts":"2026-09-22T03:32:40.033334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41319} {"level":"info","ts":"2026-09-22T03:32:40.154834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41319,"took":"117.208466ms","hash":2798073069,"current-db-size-bytes":59342848,"current-db-size":"59 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T03:32:40.154888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2798073069,"revision":41319,"compact-revision":39089} {"level":"info","ts":"2026-09-22T03:37:40.040042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43543} {"level":"info","ts":"2026-09-22T03:37:40.16753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43543,"took":"124.083017ms","hash":3754516688,"current-db-size-bytes":59342848,"current-db-size":"59 MB","current-db-size-in-use-bytes":29868032,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T03:37:40.167612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3754516688,"revision":43543,"compact-revision":41319} {"level":"info","ts":"2026-09-22T03:42:40.048865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":45828} {"level":"info","ts":"2026-09-22T03:42:40.197366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":45828,"took":"140.774395ms","hash":3414525981,"current-db-size-bytes":59342848,"current-db-size":"59 MB","current-db-size-in-use-bytes":29360128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-22T03:42:40.197487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3414525981,"revision":45828,"compact-revision":43543} {"level":"info","ts":"2026-09-22T03:43:39.134734Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T03:43:39.140702Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-09-22T03:43:39.140821Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}