{"level":"warn","ts":"2026-09-09T01:09:57.052075Z","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-09T01:09:57.052184Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.33: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.33:2380","--initial-cluster=instance=https://162.253.55.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.33: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-09T01:09:57.052257Z","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-09T01:09:57.052268Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.33:2380"]} {"level":"info","ts":"2026-09-09T01:09:57.052288Z","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-09T01:09:57.053037Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.33:2379"]} {"level":"info","ts":"2026-09-09T01:09:57.053122Z","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.33:2380"],"listen-peer-urls":["https://162.253.55.33:2380"],"advertise-client-urls":["https://162.253.55.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.33: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-09T01:09:57.056187Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.766097ms"} {"level":"info","ts":"2026-09-09T01:09:57.062098Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b85a21b07385eadf","cluster-id":"ce9267b3360d712c"} {"level":"info","ts":"2026-09-09T01:09:57.062166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:09:57.062195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf became follower at term 0"} {"level":"info","ts":"2026-09-09T01:09:57.062213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b85a21b07385eadf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:09:57.062223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf became follower at term 1"} {"level":"info","ts":"2026-09-09T01:09:57.062264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf switched to configuration voters=(13283967092618816223)"} {"level":"warn","ts":"2026-09-09T01:09:57.067456Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:09:57.068884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:09:57.070861Z","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-09T01:09:57.073455Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b85a21b07385eadf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:09:57.073723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b85a21b07385eadf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:09:57.073831Z","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-09T01:09:57.073906Z","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-09T01:09:57.073932Z","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-09T01:09:57.075655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf switched to configuration voters=(13283967092618816223)"} {"level":"info","ts":"2026-09-09T01:09:57.075925Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce9267b3360d712c","local-member-id":"b85a21b07385eadf","added-peer-id":"b85a21b07385eadf","added-peer-peer-urls":["https://162.253.55.33:2380"]} {"level":"info","ts":"2026-09-09T01:09:57.077471Z","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-09T01:09:57.077548Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.33:2380"} {"level":"info","ts":"2026-09-09T01:09:57.07759Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.33:2380"} {"level":"info","ts":"2026-09-09T01:09:57.077767Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b85a21b07385eadf","initial-advertise-peer-urls":["https://162.253.55.33:2380"],"listen-peer-urls":["https://162.253.55.33:2380"],"advertise-client-urls":["https://162.253.55.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:09:57.077822Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:09:57.963198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:09:57.963275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:09:57.963327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf received MsgPreVoteResp from b85a21b07385eadf at term 1"} {"level":"info","ts":"2026-09-09T01:09:57.963348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:09:57.963358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf received MsgVoteResp from b85a21b07385eadf at term 2"} {"level":"info","ts":"2026-09-09T01:09:57.963403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b85a21b07385eadf became leader at term 2"} {"level":"info","ts":"2026-09-09T01:09:57.963421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b85a21b07385eadf elected leader b85a21b07385eadf at term 2"} {"level":"info","ts":"2026-09-09T01:09:57.965037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:57.96603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:57.96614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:57.966025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b85a21b07385eadf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.33:2379]}","request-path":"/0/members/b85a21b07385eadf/attributes","cluster-id":"ce9267b3360d712c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:09:57.966478Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:09:57.966569Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:09:57.966818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce9267b3360d712c","local-member-id":"b85a21b07385eadf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:57.966942Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:57.966978Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:57.969587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.33:2379"} {"level":"info","ts":"2026-09-09T01:09:57.969888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:10:03.821458Z","caller":"traceutil/trace.go:171","msg":"trace[1117510554] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"102.92902ms","start":"2026-09-09T01:10:03.718499Z","end":"2026-09-09T01:10:03.821428Z","steps":["trace[1117510554] 'process raft request' (duration: 102.758846ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:11.351607Z","caller":"traceutil/trace.go:171","msg":"trace[9676153] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"112.397505ms","start":"2026-09-09T01:10:11.239179Z","end":"2026-09-09T01:10:11.351576Z","steps":["trace[9676153] 'process raft request' (duration: 109.701889ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971509Z","caller":"traceutil/trace.go:171","msg":"trace[1315336217] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"166.07041ms","start":"2026-09-09T01:10:37.805406Z","end":"2026-09-09T01:10:37.971476Z","steps":["trace[1315336217] 'process raft request' (duration: 165.815574ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971719Z","caller":"traceutil/trace.go:171","msg":"trace[767288947] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"178.294538ms","start":"2026-09-09T01:10:37.793359Z","end":"2026-09-09T01:10:37.971653Z","steps":["trace[767288947] 'process raft request' (duration: 172.144458ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971829Z","caller":"traceutil/trace.go:171","msg":"trace[1423483152] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"163.893308ms","start":"2026-09-09T01:10:37.807922Z","end":"2026-09-09T01:10:37.971815Z","steps":["trace[1423483152] 'process raft request' (duration: 163.493928ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.972003Z","caller":"traceutil/trace.go:171","msg":"trace[573039869] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"150.806609ms","start":"2026-09-09T01:10:37.821186Z","end":"2026-09-09T01:10:37.971993Z","steps":["trace[573039869] 'process raft request' (duration: 150.408089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971995Z","caller":"traceutil/trace.go:171","msg":"trace[1321150925] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"166.375218ms","start":"2026-09-09T01:10:37.805578Z","end":"2026-09-09T01:10:37.971953Z","steps":["trace[1321150925] 'process raft request' (duration: 165.709662ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971756Z","caller":"traceutil/trace.go:171","msg":"trace[785874137] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"150.43014ms","start":"2026-09-09T01:10:37.821301Z","end":"2026-09-09T01:10:37.971732Z","steps":["trace[785874137] 'process raft request' (duration: 150.351998ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.972038Z","caller":"traceutil/trace.go:171","msg":"trace[1195455404] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"157.892822ms","start":"2026-09-09T01:10:37.814091Z","end":"2026-09-09T01:10:37.971984Z","steps":["trace[1195455404] 'process raft request' (duration: 157.450711ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.971809Z","caller":"traceutil/trace.go:171","msg":"trace[920803839] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"166.111571ms","start":"2026-09-09T01:10:37.805681Z","end":"2026-09-09T01:10:37.971792Z","steps":["trace[920803839] 'process raft request' (duration: 165.684351ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.972982Z","caller":"traceutil/trace.go:171","msg":"trace[345770422] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:570; }","duration":"138.315866ms","start":"2026-09-09T01:10:37.83465Z","end":"2026-09-09T01:10:37.972966Z","steps":["trace[345770422] 'read index received' (duration: 130.931036ms)","trace[345770422] 'applied index is now lower than readState.Index' (duration: 7.38338ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:37.973059Z","caller":"traceutil/trace.go:171","msg":"trace[310653391] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"139.686229ms","start":"2026-09-09T01:10:37.833368Z","end":"2026-09-09T01:10:37.973054Z","steps":["trace[310653391] 'process raft request' (duration: 139.32847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:37.973156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.48846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:10:37.973252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.735734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:10:37.973258Z","caller":"traceutil/trace.go:171","msg":"trace[1606483626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"138.603522ms","start":"2026-09-09T01:10:37.834635Z","end":"2026-09-09T01:10:37.973239Z","steps":["trace[1606483626] 'agreement among raft nodes before linearized reading' (duration: 138.420958ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:37.973303Z","caller":"traceutil/trace.go:171","msg":"trace[1874427834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"124.807607ms","start":"2026-09-09T01:10:37.848485Z","end":"2026-09-09T01:10:37.973292Z","steps":["trace[1874427834] 'agreement among raft nodes before linearized reading' (duration: 124.739815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:37.973193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.676289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:10:37.973384Z","caller":"traceutil/trace.go:171","msg":"trace[894453413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"130.898534ms","start":"2026-09-09T01:10:37.842462Z","end":"2026-09-09T01:10:37.973361Z","steps":["trace[894453413] 'agreement among raft nodes before linearized reading' (duration: 130.611757ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:41.90353Z","caller":"traceutil/trace.go:171","msg":"trace[1902275792] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"107.690789ms","start":"2026-09-09T01:10:41.795809Z","end":"2026-09-09T01:10:41.9035Z","steps":["trace[1902275792] 'process raft request' (duration: 107.050134ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:44.778884Z","caller":"traceutil/trace.go:171","msg":"trace[335626506] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"158.168048ms","start":"2026-09-09T01:10:44.620684Z","end":"2026-09-09T01:10:44.778852Z","steps":["trace[335626506] 'process raft request' (duration: 157.530483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:44.987757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.289271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:44.987949Z","caller":"traceutil/trace.go:171","msg":"trace[1399177495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"151.540087ms","start":"2026-09-09T01:10:44.836385Z","end":"2026-09-09T01:10:44.987925Z","steps":["trace[1399177495] 'range keys from in-memory index tree' (duration: 151.137377ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:44.987932Z","caller":"traceutil/trace.go:171","msg":"trace[710907550] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"121.726382ms","start":"2026-09-09T01:10:44.866163Z","end":"2026-09-09T01:10:44.98789Z","steps":["trace[710907550] 'process raft request' (duration: 99.989653ms)","trace[710907550] 'compare' (duration: 21.437392ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:45.616893Z","caller":"traceutil/trace.go:171","msg":"trace[735048244] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"219.06262ms","start":"2026-09-09T01:10:45.397795Z","end":"2026-09-09T01:10:45.616858Z","steps":["trace[735048244] 'process raft request' (duration: 216.713133ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:02.349737Z","caller":"traceutil/trace.go:171","msg":"trace[466993754] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"116.328128ms","start":"2026-09-09T01:11:02.233371Z","end":"2026-09-09T01:11:02.349699Z","steps":["trace[466993754] 'process raft request' (duration: 116.182874ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:02.413924Z","caller":"traceutil/trace.go:171","msg":"trace[129130378] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"180.478687ms","start":"2026-09-09T01:11:02.233427Z","end":"2026-09-09T01:11:02.413905Z","steps":["trace[129130378] 'process raft request' (duration: 180.303742ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:02.414215Z","caller":"traceutil/trace.go:171","msg":"trace[1430364066] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:839; }","duration":"132.249482ms","start":"2026-09-09T01:11:02.281897Z","end":"2026-09-09T01:11:02.414146Z","steps":["trace[1430364066] 'read index received' (duration: 67.797106ms)","trace[1430364066] 'applied index is now lower than readState.Index' (duration: 64.450326ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:02.414317Z","caller":"traceutil/trace.go:171","msg":"trace[906788532] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"180.285191ms","start":"2026-09-09T01:11:02.234022Z","end":"2026-09-09T01:11:02.414307Z","steps":["trace[906788532] 'process raft request' (duration: 179.83806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:02.41452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.608691ms","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-09T01:11:02.41458Z","caller":"traceutil/trace.go:171","msg":"trace[366809349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"132.719954ms","start":"2026-09-09T01:11:02.281841Z","end":"2026-09-09T01:11:02.414561Z","steps":["trace[366809349] 'agreement among raft nodes before linearized reading' (duration: 132.493808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:53.804012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.984469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-5vvvj\" ","response":"range_response_count:1 size:4178"} {"level":"info","ts":"2026-09-09T01:11:53.804126Z","caller":"traceutil/trace.go:171","msg":"trace[1379050711] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-5vvvj; range_end:; response_count:1; response_revision:1470; }","duration":"119.185414ms","start":"2026-09-09T01:11:53.684912Z","end":"2026-09-09T01:11:53.804098Z","steps":["trace[1379050711] 'range keys from in-memory index tree' (duration: 118.754332ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:21.52672Z","caller":"traceutil/trace.go:171","msg":"trace[576408943] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"272.350313ms","start":"2026-09-09T01:12:21.254343Z","end":"2026-09-09T01:12:21.526693Z","steps":["trace[576408943] 'read index received' (duration: 272.162608ms)","trace[576408943] 'applied index is now lower than readState.Index' (duration: 186.515µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:21.526772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:12:21.190748Z","time spent":"336.015529ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-09T01:12:21.526945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.59528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8404710b-6597-4445-9e45-d46afbaec29f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-09T01:12:21.526986Z","caller":"traceutil/trace.go:171","msg":"trace[946868329] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8404710b-6597-4445-9e45-d46afbaec29f; range_end:; response_count:1; response_revision:1894; }","duration":"272.680262ms","start":"2026-09-09T01:12:21.254294Z","end":"2026-09-09T01:12:21.526975Z","steps":["trace[946868329] 'agreement among raft nodes before linearized reading' (duration: 272.502407ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:28.162574Z","caller":"traceutil/trace.go:171","msg":"trace[1024837286] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"123.580851ms","start":"2026-09-09T01:12:28.038964Z","end":"2026-09-09T01:12:28.162545Z","steps":["trace[1024837286] 'process raft request' (duration: 123.367815ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:35.452856Z","caller":"traceutil/trace.go:171","msg":"trace[204686758] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"121.145506ms","start":"2026-09-09T01:12:35.331691Z","end":"2026-09-09T01:12:35.452837Z","steps":["trace[204686758] 'process raft request' (duration: 120.987932ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:37.887403Z","caller":"traceutil/trace.go:171","msg":"trace[569087993] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"139.674819ms","start":"2026-09-09T01:12:37.747703Z","end":"2026-09-09T01:12:37.887378Z","steps":["trace[569087993] 'read index received' (duration: 139.467464ms)","trace[569087993] 'applied index is now lower than readState.Index' (duration: 206.385µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:12:37.887588Z","caller":"traceutil/trace.go:171","msg":"trace[347566776] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"153.465757ms","start":"2026-09-09T01:12:37.734101Z","end":"2026-09-09T01:12:37.887567Z","steps":["trace[347566776] 'process raft request' (duration: 153.129498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:37.88763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.918596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-09T01:12:37.887665Z","caller":"traceutil/trace.go:171","msg":"trace[329249504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2029; }","duration":"140.008979ms","start":"2026-09-09T01:12:37.747645Z","end":"2026-09-09T01:12:37.887654Z","steps":["trace[329249504] 'agreement among raft nodes before linearized reading' (duration: 139.825904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:12.358627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.063807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:13:12.358793Z","caller":"traceutil/trace.go:171","msg":"trace[1375969519] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"252.600617ms","start":"2026-09-09T01:13:12.106162Z","end":"2026-09-09T01:13:12.358763Z","steps":["trace[1375969519] 'process raft request' (duration: 110.530214ms)","trace[1375969519] 'compare' (duration: 140.749908ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:13:12.60838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.736732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:13:12.608501Z","caller":"traceutil/trace.go:171","msg":"trace[383217582] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2219; }","duration":"121.925818ms","start":"2026-09-09T01:13:12.486547Z","end":"2026-09-09T01:13:12.608473Z","steps":["trace[383217582] 'range keys from in-memory index tree' (duration: 121.401553ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:56.863903Z","caller":"traceutil/trace.go:171","msg":"trace[287715260] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"131.310787ms","start":"2026-09-09T01:13:56.732541Z","end":"2026-09-09T01:13:56.863851Z","steps":["trace[287715260] 'process raft request' (duration: 54.481151ms)","trace[287715260] 'compare' (duration: 76.561489ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:56.863981Z","caller":"traceutil/trace.go:171","msg":"trace[2090668475] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"131.303228ms","start":"2026-09-09T01:13:56.732617Z","end":"2026-09-09T01:13:56.86392Z","steps":["trace[2090668475] 'process raft request' (duration: 131.130064ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:56.864079Z","caller":"traceutil/trace.go:171","msg":"trace[19914329] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"127.747916ms","start":"2026-09-09T01:13:56.736294Z","end":"2026-09-09T01:13:56.864042Z","steps":["trace[19914329] 'process raft request' (duration: 127.50876ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:00.566453Z","caller":"traceutil/trace.go:171","msg":"trace[1533224903] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"279.110629ms","start":"2026-09-09T01:14:00.287306Z","end":"2026-09-09T01:14:00.566416Z","steps":["trace[1533224903] 'process raft request' (duration: 278.916174ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:35.066868Z","caller":"traceutil/trace.go:171","msg":"trace[1527974906] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"330.269295ms","start":"2026-09-09T01:14:34.736564Z","end":"2026-09-09T01:14:35.066833Z","steps":["trace[1527974906] 'process raft request' (duration: 330.032668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.067281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:14:34.736531Z","time spent":"330.443829ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:14:35.214649Z","caller":"traceutil/trace.go:171","msg":"trace[1061695594] linearizableReadLoop","detail":"{readStateIndex:2965; appliedIndex:2963; }","duration":"406.764971ms","start":"2026-09-09T01:14:34.807846Z","end":"2026-09-09T01:14:35.214611Z","steps":["trace[1061695594] 'read index received' (duration: 258.786105ms)","trace[1061695594] 'applied index is now lower than readState.Index' (duration: 147.977196ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:14:35.214719Z","caller":"traceutil/trace.go:171","msg":"trace[660640232] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"476.627308ms","start":"2026-09-09T01:14:34.738017Z","end":"2026-09-09T01:14:35.214645Z","steps":["trace[660640232] 'process raft request' (duration: 476.284639ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:35.214739Z","caller":"traceutil/trace.go:171","msg":"trace[1208051445] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"182.31785ms","start":"2026-09-09T01:14:35.032378Z","end":"2026-09-09T01:14:35.214696Z","steps":["trace[1208051445] 'process raft request' (duration: 182.162606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.214984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.127821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-09T01:14:35.215051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:14:34.737985Z","time spent":"476.836204ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:14:35.215091Z","caller":"traceutil/trace.go:171","msg":"trace[1474985923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2859; }","duration":"407.281535ms","start":"2026-09-09T01:14:34.807774Z","end":"2026-09-09T01:14:35.215056Z","steps":["trace[1474985923] 'agreement among raft nodes before linearized reading' (duration: 407.037439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.215142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:14:34.807748Z","time spent":"407.378018ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T01:14:35.21999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.299003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-09T01:14:35.220115Z","caller":"traceutil/trace.go:171","msg":"trace[874726086] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2860; }","duration":"305.451087ms","start":"2026-09-09T01:14:34.914612Z","end":"2026-09-09T01:14:35.220063Z","steps":["trace[874726086] 'agreement among raft nodes before linearized reading' (duration: 305.1725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.220214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:14:34.914588Z","time spent":"305.60991ms","remote":"127.0.0.1:44062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-09-09T01:14:35.220827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.428461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d38209aa6fe259\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-09T01:14:35.220885Z","caller":"traceutil/trace.go:171","msg":"trace[2012321905] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d38209aa6fe259; range_end:; response_count:1; response_revision:2860; }","duration":"194.548953ms","start":"2026-09-09T01:14:35.02632Z","end":"2026-09-09T01:14:35.220869Z","steps":["trace[2012321905] 'agreement among raft nodes before linearized reading' (duration: 194.391779ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:46.753212Z","caller":"traceutil/trace.go:171","msg":"trace[592250497] linearizableReadLoop","detail":"{readStateIndex:4220; appliedIndex:4219; }","duration":"142.426522ms","start":"2026-09-09T01:17:46.610754Z","end":"2026-09-09T01:17:46.753181Z","steps":["trace[592250497] 'read index received' (duration: 142.051002ms)","trace[592250497] 'applied index is now lower than readState.Index' (duration: 374.07µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:17:46.753385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.616838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:17:46.753438Z","caller":"traceutil/trace.go:171","msg":"trace[1279405569] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4068; }","duration":"142.719391ms","start":"2026-09-09T01:17:46.610705Z","end":"2026-09-09T01:17:46.753424Z","steps":["trace[1279405569] 'agreement among raft nodes before linearized reading' (duration: 142.626728ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:46.753388Z","caller":"traceutil/trace.go:171","msg":"trace[1188797951] transaction","detail":"{read_only:false; response_revision:4068; number_of_response:1; }","duration":"165.095863ms","start":"2026-09-09T01:17:46.588217Z","end":"2026-09-09T01:17:46.753312Z","steps":["trace[1188797951] 'process raft request' (duration: 164.60032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:52.27819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.7161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:17:52.278303Z","caller":"traceutil/trace.go:171","msg":"trace[1701209188] linearizableReadLoop","detail":"{readStateIndex:4253; appliedIndex:4251; }","duration":"314.246872ms","start":"2026-09-09T01:17:51.964037Z","end":"2026-09-09T01:17:52.278284Z","steps":["trace[1701209188] 'read index received' (duration: 13.704033ms)","trace[1701209188] 'applied index is now lower than readState.Index' (duration: 300.541259ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:17:52.278401Z","caller":"traceutil/trace.go:171","msg":"trace[58404039] transaction","detail":"{read_only:false; response_revision:4099; number_of_response:1; }","duration":"315.938367ms","start":"2026-09-09T01:17:51.962445Z","end":"2026-09-09T01:17:52.278383Z","steps":["trace[58404039] 'process raft request' (duration: 178.928049ms)","trace[58404039] 'compare' (duration: 136.392052ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:17:52.278468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:51.962424Z","time spent":"316.01294ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:17:52.278575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.454156ms","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:5"} {"level":"info","ts":"2026-09-09T01:17:52.278671Z","caller":"traceutil/trace.go:171","msg":"trace[827697655] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4099; }","duration":"138.61728ms","start":"2026-09-09T01:17:52.140035Z","end":"2026-09-09T01:17:52.278652Z","steps":["trace[827697655] 'agreement among raft nodes before linearized reading' (duration: 138.443526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:52.278664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.614842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n62mq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T01:17:52.278751Z","caller":"traceutil/trace.go:171","msg":"trace[1762292990] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n62mq; range_end:; response_count:1; response_revision:4099; }","duration":"314.735265ms","start":"2026-09-09T01:17:51.963993Z","end":"2026-09-09T01:17:52.278729Z","steps":["trace[1762292990] 'agreement among raft nodes before linearized reading' (duration: 314.509919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:52.278795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:51.963982Z","time spent":"314.805037ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-n62mq\" "} {"level":"info","ts":"2026-09-09T01:19:25.327906Z","caller":"traceutil/trace.go:171","msg":"trace[944053004] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"123.965486ms","start":"2026-09-09T01:19:25.203904Z","end":"2026-09-09T01:19:25.327869Z","steps":["trace[944053004] 'process raft request' (duration: 51.749688ms)","trace[944053004] 'compare' (duration: 72.051054ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:26.175774Z","caller":"traceutil/trace.go:171","msg":"trace[1906586538] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"111.362143ms","start":"2026-09-09T01:19:26.064384Z","end":"2026-09-09T01:19:26.175746Z","steps":["trace[1906586538] 'process raft request' (duration: 111.290601ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:26.175782Z","caller":"traceutil/trace.go:171","msg":"trace[1063885398] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"112.154434ms","start":"2026-09-09T01:19:26.063542Z","end":"2026-09-09T01:19:26.175697Z","steps":["trace[1063885398] 'process raft request' (duration: 109.144464ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:26.176058Z","caller":"traceutil/trace.go:171","msg":"trace[653822865] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"112.36799ms","start":"2026-09-09T01:19:26.063647Z","end":"2026-09-09T01:19:26.176015Z","steps":["trace[653822865] 'process raft request' (duration: 111.96293ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:26.176182Z","caller":"traceutil/trace.go:171","msg":"trace[353594540] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"103.713441ms","start":"2026-09-09T01:19:26.072448Z","end":"2026-09-09T01:19:26.176162Z","steps":["trace[353594540] 'process raft request' (duration: 103.67329ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:26.176175Z","caller":"traceutil/trace.go:171","msg":"trace[688582058] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"110.665735ms","start":"2026-09-09T01:19:26.065486Z","end":"2026-09-09T01:19:26.176151Z","steps":["trace[688582058] 'process raft request' (duration: 110.550632ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:26.176255Z","caller":"traceutil/trace.go:171","msg":"trace[651835788] linearizableReadLoop","detail":"{readStateIndex:5411; appliedIndex:5406; }","duration":"104.843492ms","start":"2026-09-09T01:19:26.071361Z","end":"2026-09-09T01:19:26.176204Z","steps":["trace[651835788] 'read index received' (duration: 101.39776ms)","trace[651835788] 'applied index is now lower than readState.Index' (duration: 3.440841ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:26.17614Z","caller":"traceutil/trace.go:171","msg":"trace[379266771] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"110.699826ms","start":"2026-09-09T01:19:26.065417Z","end":"2026-09-09T01:19:26.176117Z","steps":["trace[379266771] 'process raft request' (duration: 110.290615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:26.176553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.17586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6rnsn\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-09-09T01:19:26.1766Z","caller":"traceutil/trace.go:171","msg":"trace[1210318810] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6rnsn; range_end:; response_count:1; response_revision:5213; }","duration":"105.263653ms","start":"2026-09-09T01:19:26.071324Z","end":"2026-09-09T01:19:26.176587Z","steps":["trace[1210318810] 'agreement among raft nodes before linearized reading' (duration: 105.037547ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:32.422345Z","caller":"traceutil/trace.go:171","msg":"trace[1906692665] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"107.566744ms","start":"2026-09-09T01:19:32.314748Z","end":"2026-09-09T01:19:32.422315Z","steps":["trace[1906692665] 'process raft request' (duration: 107.399999ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:32.464267Z","caller":"traceutil/trace.go:171","msg":"trace[453657371] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"109.728689ms","start":"2026-09-09T01:19:32.354514Z","end":"2026-09-09T01:19:32.464242Z","steps":["trace[453657371] 'process raft request' (duration: 109.608366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:42.572518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.464792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:19:42.573125Z","caller":"traceutil/trace.go:171","msg":"trace[1612749460] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"147.300584ms","start":"2026-09-09T01:19:42.425803Z","end":"2026-09-09T01:19:42.573104Z","steps":["trace[1612749460] 'process raft request' (duration: 45.193215ms)","trace[1612749460] 'compare' (duration: 101.340999ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:51.174245Z","caller":"traceutil/trace.go:171","msg":"trace[1851288445] transaction","detail":"{read_only:false; response_revision:5743; number_of_response:1; }","duration":"138.275075ms","start":"2026-09-09T01:19:51.03594Z","end":"2026-09-09T01:19:51.174215Z","steps":["trace[1851288445] 'process raft request' (duration: 88.062718ms)","trace[1851288445] 'compare' (duration: 49.798386ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:51.514233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.725957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:19:51.514639Z","caller":"traceutil/trace.go:171","msg":"trace[176774690] linearizableReadLoop","detail":"{readStateIndex:5960; appliedIndex:5957; }","duration":"319.019883ms","start":"2026-09-09T01:19:51.195597Z","end":"2026-09-09T01:19:51.514617Z","steps":["trace[176774690] 'read index received' (duration: 210.72187ms)","trace[176774690] 'applied index is now lower than readState.Index' (duration: 108.296113ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:51.514718Z","caller":"traceutil/trace.go:171","msg":"trace[957121832] transaction","detail":"{read_only:false; response_revision:5746; number_of_response:1; }","duration":"334.459551ms","start":"2026-09-09T01:19:51.180229Z","end":"2026-09-09T01:19:51.514689Z","steps":["trace[957121832] 'process raft request' (duration: 226.151498ms)","trace[957121832] 'compare' (duration: 107.409439ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:51.514731Z","caller":"traceutil/trace.go:171","msg":"trace[1302866161] transaction","detail":"{read_only:false; response_revision:5747; number_of_response:1; }","duration":"327.108916ms","start":"2026-09-09T01:19:51.187603Z","end":"2026-09-09T01:19:51.514712Z","steps":["trace[1302866161] 'process raft request' (duration: 326.753247ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.514771Z","caller":"traceutil/trace.go:171","msg":"trace[1212280681] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5747; }","duration":"323.288646ms","start":"2026-09-09T01:19:51.191443Z","end":"2026-09-09T01:19:51.514732Z","steps":["trace[1212280681] 'process raft request' (duration: 323.06253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.514876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.187578Z","time spent":"327.195619ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2479,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.51478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.180208Z","time spent":"334.538392ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.515009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.191416Z","time spent":"323.48676ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":2529,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.515001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.656029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-09T01:19:51.515106Z","caller":"traceutil/trace.go:171","msg":"trace[1688109074] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5747; }","duration":"336.820393ms","start":"2026-09-09T01:19:51.178263Z","end":"2026-09-09T01:19:51.515084Z","steps":["trace[1688109074] 'agreement among raft nodes before linearized reading' (duration: 336.463404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.515095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.579203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:19:51.515153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.178245Z","time spent":"336.899395ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-09-09T01:19:51.515193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.740878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9555add3-643e-4f0d-a245-b41d84f8482d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-09T01:19:51.515232Z","caller":"traceutil/trace.go:171","msg":"trace[951861009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5747; }","duration":"225.746827ms","start":"2026-09-09T01:19:51.289468Z","end":"2026-09-09T01:19:51.515215Z","steps":["trace[951861009] 'agreement among raft nodes before linearized reading' (duration: 225.554772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.515335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.848883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-09-09T01:19:51.515357Z","caller":"traceutil/trace.go:171","msg":"trace[1240052598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9555add3-643e-4f0d-a245-b41d84f8482d; range_end:; response_count:1; response_revision:5747; }","duration":"306.867131ms","start":"2026-09-09T01:19:51.208416Z","end":"2026-09-09T01:19:51.515284Z","steps":["trace[1240052598] 'agreement among raft nodes before linearized reading' (duration: 306.536553ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.515391Z","caller":"traceutil/trace.go:171","msg":"trace[1472704883] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5747; }","duration":"280.941636ms","start":"2026-09-09T01:19:51.234432Z","end":"2026-09-09T01:19:51.515374Z","steps":["trace[1472704883] 'agreement among raft nodes before linearized reading' (duration: 280.803452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.51541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.208403Z","time spent":"306.991824ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9555add3-643e-4f0d-a245-b41d84f8482d\" "} {"level":"warn","ts":"2026-09-09T01:19:51.515422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.77806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9555add3-643e-4f0d-a245-b41d84f8482d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-09T01:19:51.515475Z","caller":"traceutil/trace.go:171","msg":"trace[134522107] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9555add3-643e-4f0d-a245-b41d84f8482d; range_end:; response_count:1; response_revision:5747; }","duration":"191.862812ms","start":"2026-09-09T01:19:51.323594Z","end":"2026-09-09T01:19:51.515457Z","steps":["trace[134522107] 'agreement among raft nodes before linearized reading' (duration: 191.610445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.51549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.601585ms","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-09T01:19:51.515516Z","caller":"traceutil/trace.go:171","msg":"trace[1059169971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5747; }","duration":"278.643235ms","start":"2026-09-09T01:19:51.236865Z","end":"2026-09-09T01:19:51.515508Z","steps":["trace[1059169971] 'agreement among raft nodes before linearized reading' (duration: 278.562123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.516403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.629316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-09T01:19:51.516471Z","caller":"traceutil/trace.go:171","msg":"trace[277379606] range","detail":"{range_begin:/registry/masterleases/162.253.55.33; range_end:; response_count:1; response_revision:5747; }","duration":"303.726608ms","start":"2026-09-09T01:19:51.21273Z","end":"2026-09-09T01:19:51.516457Z","steps":["trace[277379606] 'agreement among raft nodes before linearized reading' (duration: 302.723822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.516491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.212708Z","time spent":"303.777129ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.33\" "} {"level":"warn","ts":"2026-09-09T01:19:51.961303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.374118ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-09T01:19:51.961377Z","caller":"traceutil/trace.go:171","msg":"trace[1831792126] linearizableReadLoop","detail":"{readStateIndex:5961; appliedIndex:5960; }","duration":"441.436948ms","start":"2026-09-09T01:19:51.519927Z","end":"2026-09-09T01:19:51.961364Z","steps":["trace[1831792126] 'read index received' (duration: 123.961667ms)","trace[1831792126] 'applied index is now lower than readState.Index' (duration: 317.474031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:51.961509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.581172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-w44rc\" ","response":"range_response_count:1 size:2600"} {"level":"info","ts":"2026-09-09T01:19:51.961528Z","caller":"traceutil/trace.go:171","msg":"trace[1189680245] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-w44rc; range_end:; response_count:1; response_revision:5747; }","duration":"441.626403ms","start":"2026-09-09T01:19:51.519895Z","end":"2026-09-09T01:19:51.961522Z","steps":["trace[1189680245] 'agreement among raft nodes before linearized reading' (duration: 441.52063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.961546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.519882Z","time spent":"441.658434ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":2624,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-w44rc\" "} {"level":"warn","ts":"2026-09-09T01:19:51.961411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.517414Z","time spent":"443.990555ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-09T01:19:51.978659Z","caller":"traceutil/trace.go:171","msg":"trace[1292925868] transaction","detail":"{read_only:false; response_revision:5748; number_of_response:1; }","duration":"458.361626ms","start":"2026-09-09T01:19:51.520277Z","end":"2026-09-09T01:19:51.978639Z","steps":["trace[1292925868] 'process raft request' (duration: 458.114049ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.978668Z","caller":"traceutil/trace.go:171","msg":"trace[254165217] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"457.684888ms","start":"2026-09-09T01:19:51.520965Z","end":"2026-09-09T01:19:51.97865Z","steps":["trace[254165217] 'process raft request' (duration: 457.588766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.978776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.520265Z","time spent":"458.445768ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.978776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.520928Z","time spent":"457.798021ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:51.978965Z","caller":"traceutil/trace.go:171","msg":"trace[2137324412] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"457.143873ms","start":"2026-09-09T01:19:51.521812Z","end":"2026-09-09T01:19:51.978956Z","steps":["trace[2137324412] 'process raft request' (duration: 456.794034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.521804Z","time spent":"457.183025ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":840,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:19:51.979143Z","caller":"traceutil/trace.go:171","msg":"trace[181838342] transaction","detail":"{read_only:false; response_revision:5752; number_of_response:1; }","duration":"443.326678ms","start":"2026-09-09T01:19:51.535808Z","end":"2026-09-09T01:19:51.979135Z","steps":["trace[181838342] 'process raft request' (duration: 443.151753ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.979132Z","caller":"traceutil/trace.go:171","msg":"trace[1916791754] transaction","detail":"{read_only:false; response_revision:5751; number_of_response:1; }","duration":"455.414518ms","start":"2026-09-09T01:19:51.52367Z","end":"2026-09-09T01:19:51.979085Z","steps":["trace[1916791754] 'process raft request' (duration: 455.188982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.97928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.094793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:19:51.979319Z","caller":"traceutil/trace.go:171","msg":"trace[328574678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5753; }","duration":"423.180365ms","start":"2026-09-09T01:19:51.556127Z","end":"2026-09-09T01:19:51.979307Z","steps":["trace[328574678] 'agreement among raft nodes before linearized reading' (duration: 423.063192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.808938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"warn","ts":"2026-09-09T01:19:51.979342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.556104Z","time spent":"423.233427ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T01:19:51.979316Z","caller":"traceutil/trace.go:171","msg":"trace[1029183040] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"441.074669ms","start":"2026-09-09T01:19:51.538215Z","end":"2026-09-09T01:19:51.97929Z","steps":["trace[1029183040] 'process raft request' (duration: 440.883744ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.979358Z","caller":"traceutil/trace.go:171","msg":"trace[1329594719] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5753; }","duration":"458.891329ms","start":"2026-09-09T01:19:51.520451Z","end":"2026-09-09T01:19:51.979342Z","steps":["trace[1329594719] 'agreement among raft nodes before linearized reading' (duration: 458.721715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.68815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T01:19:51.979361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.01707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:19:51.979372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.523661Z","time spent":"455.575522ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.979397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.287108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-09T01:19:51.97942Z","caller":"traceutil/trace.go:171","msg":"trace[1569725325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5753; }","duration":"420.753311ms","start":"2026-09-09T01:19:51.558651Z","end":"2026-09-09T01:19:51.979404Z","steps":["trace[1569725325] 'agreement among raft nodes before linearized reading' (duration: 420.615667ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:51.97943Z","caller":"traceutil/trace.go:171","msg":"trace[1951214035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5753; }","duration":"143.124823ms","start":"2026-09-09T01:19:51.83629Z","end":"2026-09-09T01:19:51.979414Z","steps":["trace[1951214035] 'agreement among raft nodes before linearized reading' (duration: 142.99824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.538191Z","time spent":"441.185511ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:19:51.979468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.558646Z","time spent":"420.811842ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:19:51.979384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.520445Z","time spent":"458.93358ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3982,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-09-09T01:19:51.979472Z","caller":"traceutil/trace.go:171","msg":"trace[1475080396] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5753; }","duration":"437.383011ms","start":"2026-09-09T01:19:51.542067Z","end":"2026-09-09T01:19:51.97945Z","steps":["trace[1475080396] 'agreement among raft nodes before linearized reading' (duration: 437.188706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.542058Z","time spent":"437.452863ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-09-09T01:19:51.979314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.88242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:19:51.979637Z","caller":"traceutil/trace.go:171","msg":"trace[1627508083] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5753; }","duration":"355.245979ms","start":"2026-09-09T01:19:51.624359Z","end":"2026-09-09T01:19:51.979605Z","steps":["trace[1627508083] 'agreement among raft nodes before linearized reading' (duration: 354.815538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:51.979673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.624339Z","time spent":"355.325812ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T01:19:51.979726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:51.535776Z","time spent":"443.38576ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2630,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:58.013145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-09-09T01:19:58.08266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"67.7323ms","hash":4208004739,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:19:58.082761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208004739,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:24:58.023179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5866} {"level":"info","ts":"2026-09-09T01:24:58.128798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5866,"took":"103.565742ms","hash":2457332455,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":26550272,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:24:58.128882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2457332455,"revision":5866,"compact-revision":2991} {"level":"info","ts":"2026-09-09T01:26:22.025668Z","caller":"traceutil/trace.go:171","msg":"trace[905176817] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"197.206444ms","start":"2026-09-09T01:26:21.828429Z","end":"2026-09-09T01:26:22.025635Z","steps":["trace[905176817] 'process raft request' (duration: 196.780312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:51.203463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.509772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:26:51.203782Z","caller":"traceutil/trace.go:171","msg":"trace[455555613] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"693.742698ms","start":"2026-09-09T01:26:50.509983Z","end":"2026-09-09T01:26:51.203726Z","steps":["trace[455555613] 'process raft request' (duration: 24.869016ms)","trace[455555613] 'compare' (duration: 668.05994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:26:51.203987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:26:50.509958Z","time spent":"693.921933ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:26:51.20402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.169058ms","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:7"} {"level":"info","ts":"2026-09-09T01:26:51.204088Z","caller":"traceutil/trace.go:171","msg":"trace[1922605325] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8923; }","duration":"531.25159ms","start":"2026-09-09T01:26:50.672797Z","end":"2026-09-09T01:26:51.204048Z","steps":["trace[1922605325] 'agreement among raft nodes before linearized reading' (duration: 531.069545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:51.204165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:26:50.672777Z","time spent":"531.346612ms","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":13,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-09-09T01:26:51.203833Z","caller":"traceutil/trace.go:171","msg":"trace[963468233] linearizableReadLoop","detail":"{readStateIndex:9239; appliedIndex:9237; }","duration":"530.970173ms","start":"2026-09-09T01:26:50.672839Z","end":"2026-09-09T01:26:51.20381Z","steps":["trace[963468233] 'read index received' (duration: 371.872974ms)","trace[963468233] 'applied index is now lower than readState.Index' (duration: 159.095959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:26:51.204401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.060276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:26:51.20447Z","caller":"traceutil/trace.go:171","msg":"trace[356891416] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8923; }","duration":"165.134238ms","start":"2026-09-09T01:26:51.039319Z","end":"2026-09-09T01:26:51.204454Z","steps":["trace[356891416] 'agreement among raft nodes before linearized reading' (duration: 165.014655ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:51.204723Z","caller":"traceutil/trace.go:171","msg":"trace[1810153927] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"677.568481ms","start":"2026-09-09T01:26:50.52714Z","end":"2026-09-09T01:26:51.204708Z","steps":["trace[1810153927] 'process raft request' (duration: 676.452241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:51.204842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:26:50.527111Z","time spent":"677.650353ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:26:53.391101Z","caller":"traceutil/trace.go:171","msg":"trace[2010240430] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"180.7534ms","start":"2026-09-09T01:26:53.210329Z","end":"2026-09-09T01:26:53.391082Z","steps":["trace[2010240430] 'process raft request' (duration: 180.600546ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:53.44015Z","caller":"traceutil/trace.go:171","msg":"trace[2124583029] transaction","detail":"{read_only:false; response_revision:8935; number_of_response:1; }","duration":"226.703783ms","start":"2026-09-09T01:26:53.213405Z","end":"2026-09-09T01:26:53.440109Z","steps":["trace[2124583029] 'process raft request' (duration: 226.463187ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:59.517807Z","caller":"traceutil/trace.go:171","msg":"trace[2124972887] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"109.707335ms","start":"2026-09-09T01:26:59.408065Z","end":"2026-09-09T01:26:59.517772Z","steps":["trace[2124972887] 'process raft request' (duration: 109.410807ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:59.630654Z","caller":"traceutil/trace.go:171","msg":"trace[1084208343] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"106.645374ms","start":"2026-09-09T01:26:59.523986Z","end":"2026-09-09T01:26:59.630631Z","steps":["trace[1084208343] 'process raft request' (duration: 106.47591ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:00.120329Z","caller":"traceutil/trace.go:171","msg":"trace[1311398116] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"108.872533ms","start":"2026-09-09T01:27:00.011431Z","end":"2026-09-09T01:27:00.120303Z","steps":["trace[1311398116] 'process raft request' (duration: 108.704259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:27:00.417386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.881417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-09T01:27:00.417475Z","caller":"traceutil/trace.go:171","msg":"trace[891890073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8969; }","duration":"141.026751ms","start":"2026-09-09T01:27:00.27643Z","end":"2026-09-09T01:27:00.417456Z","steps":["trace[891890073] 'range keys from in-memory index tree' (duration: 140.642061ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:05.58593Z","caller":"traceutil/trace.go:171","msg":"trace[205709757] linearizableReadLoop","detail":"{readStateIndex:9327; appliedIndex:9326; }","duration":"233.386329ms","start":"2026-09-09T01:27:05.352517Z","end":"2026-09-09T01:27:05.585903Z","steps":["trace[205709757] 'read index received' (duration: 233.195434ms)","trace[205709757] 'applied index is now lower than readState.Index' (duration: 189.305µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:27:05.586013Z","caller":"traceutil/trace.go:171","msg":"trace[39636376] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"237.433276ms","start":"2026-09-09T01:27:05.348534Z","end":"2026-09-09T01:27:05.585968Z","steps":["trace[39636376] 'process raft request' (duration: 237.085087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:27:05.586157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.619095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6tq7w\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-09-09T01:27:05.586211Z","caller":"traceutil/trace.go:171","msg":"trace[669625900] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6tq7w; range_end:; response_count:1; response_revision:9008; }","duration":"233.764619ms","start":"2026-09-09T01:27:05.352436Z","end":"2026-09-09T01:27:05.586201Z","steps":["trace[669625900] 'agreement among raft nodes before linearized reading' (duration: 233.566784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:27:05.81842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.61196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:27:05.818627Z","caller":"traceutil/trace.go:171","msg":"trace[196930317] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9327; }","duration":"228.925871ms","start":"2026-09-09T01:27:05.589682Z","end":"2026-09-09T01:27:05.818608Z","steps":["trace[196930317] 'read index received' (duration: 110.015423ms)","trace[196930317] 'applied index is now lower than readState.Index' (duration: 118.909268ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:27:05.818811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.093456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6tq7w\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-09-09T01:27:05.818866Z","caller":"traceutil/trace.go:171","msg":"trace[1210611837] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6tq7w; range_end:; response_count:1; response_revision:9010; }","duration":"229.200178ms","start":"2026-09-09T01:27:05.58965Z","end":"2026-09-09T01:27:05.81885Z","steps":["trace[1210611837] 'agreement among raft nodes before linearized reading' (duration: 229.088655ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:05.818907Z","caller":"traceutil/trace.go:171","msg":"trace[1472560335] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"463.611904ms","start":"2026-09-09T01:27:05.355271Z","end":"2026-09-09T01:27:05.818882Z","steps":["trace[1472560335] 'process raft request' (duration: 344.443419ms)","trace[1472560335] 'compare' (duration: 118.457327ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:27:05.819009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:27:05.355258Z","time spent":"463.694107ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:27:05.819202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.800711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-09T01:27:05.819244Z","caller":"traceutil/trace.go:171","msg":"trace[879306732] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9010; }","duration":"180.878983ms","start":"2026-09-09T01:27:05.638353Z","end":"2026-09-09T01:27:05.819232Z","steps":["trace[879306732] 'agreement among raft nodes before linearized reading' (duration: 180.7416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:27:05.819607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.588961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:27:05.819657Z","caller":"traceutil/trace.go:171","msg":"trace[2062610023] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9010; }","duration":"162.658323ms","start":"2026-09-09T01:27:05.65698Z","end":"2026-09-09T01:27:05.819638Z","steps":["trace[2062610023] 'agreement among raft nodes before linearized reading' (duration: 162.5371ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:05.82101Z","caller":"traceutil/trace.go:171","msg":"trace[1115946611] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"278.810028ms","start":"2026-09-09T01:27:05.5399Z","end":"2026-09-09T01:27:05.81871Z","steps":["trace[1115946611] 'process raft request' (duration: 278.626933ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:26.713531Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000254b.wal"} {"level":"info","ts":"2026-09-09T01:28:07.036142Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b85a21b07385eadf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:28:07.045859Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:28:07.046039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T01:28:30.968977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.841781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:28:30.96907Z","caller":"traceutil/trace.go:171","msg":"trace[205749718] transaction","detail":"{read_only:false; response_revision:9836; number_of_response:1; }","duration":"135.370138ms","start":"2026-09-09T01:28:30.833604Z","end":"2026-09-09T01:28:30.968974Z","steps":["trace[205749718] 'process raft request' (duration: 127.910791ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:28:30.969162Z","caller":"traceutil/trace.go:171","msg":"trace[1649195350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9836; }","duration":"130.087898ms","start":"2026-09-09T01:28:30.839051Z","end":"2026-09-09T01:28:30.969139Z","steps":["trace[1649195350] 'agreement among raft nodes before linearized reading' (duration: 129.79283ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:28:31.17261Z","caller":"traceutil/trace.go:171","msg":"trace[1556010327] linearizableReadLoop","detail":"{readStateIndex:10182; appliedIndex:10181; }","duration":"165.974454ms","start":"2026-09-09T01:28:31.006502Z","end":"2026-09-09T01:28:31.172477Z","steps":["trace[1556010327] 'read index received' (duration: 101.462904ms)","trace[1556010327] 'applied index is now lower than readState.Index' (duration: 64.50993ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:28:31.172734Z","caller":"traceutil/trace.go:171","msg":"trace[1350189157] transaction","detail":"{read_only:false; response_revision:9838; number_of_response:1; }","duration":"169.23732ms","start":"2026-09-09T01:28:31.003468Z","end":"2026-09-09T01:28:31.172705Z","steps":["trace[1350189157] 'process raft request' (duration: 104.459533ms)","trace[1350189157] 'compare' (duration: 64.11788ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:28:31.172806Z","caller":"traceutil/trace.go:171","msg":"trace[1850358031] transaction","detail":"{read_only:false; response_revision:9839; number_of_response:1; }","duration":"136.23871ms","start":"2026-09-09T01:28:31.036528Z","end":"2026-09-09T01:28:31.172767Z","steps":["trace[1850358031] 'process raft request' (duration: 135.826569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:28:31.172995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.437616ms","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-09T01:28:31.173069Z","caller":"traceutil/trace.go:171","msg":"trace[197836145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9839; }","duration":"166.574939ms","start":"2026-09-09T01:28:31.006476Z","end":"2026-09-09T01:28:31.173051Z","steps":["trace[197836145] 'agreement among raft nodes before linearized reading' (duration: 166.266931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:28:31.173123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.9142ms","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-09T01:28:31.173219Z","caller":"traceutil/trace.go:171","msg":"trace[189105716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9839; }","duration":"161.057714ms","start":"2026-09-09T01:28:31.012138Z","end":"2026-09-09T01:28:31.173196Z","steps":["trace[189105716] 'agreement among raft nodes before linearized reading' (duration: 160.798287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:28:31.17326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.536932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:28:31.173506Z","caller":"traceutil/trace.go:171","msg":"trace[1598707068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9839; }","duration":"165.740428ms","start":"2026-09-09T01:28:31.007591Z","end":"2026-09-09T01:28:31.173331Z","steps":["trace[1598707068] 'agreement among raft nodes before linearized reading' (duration: 165.337937ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:29:58.031253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8041} {"level":"info","ts":"2026-09-09T01:29:58.111082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8041,"took":"77.643436ms","hash":1456051590,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":28098560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:29:58.111153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1456051590,"revision":8041,"compact-revision":5866} {"level":"info","ts":"2026-09-09T01:33:54.545292Z","caller":"traceutil/trace.go:171","msg":"trace[1566281000] transaction","detail":"{read_only:false; response_revision:12183; number_of_response:1; }","duration":"220.966174ms","start":"2026-09-09T01:33:54.324289Z","end":"2026-09-09T01:33:54.545256Z","steps":["trace[1566281000] 'process raft request' (duration: 220.40247ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:33:54.545736Z","caller":"traceutil/trace.go:171","msg":"trace[859829413] linearizableReadLoop","detail":"{readStateIndex:12605; appliedIndex:12603; }","duration":"122.537849ms","start":"2026-09-09T01:33:54.423168Z","end":"2026-09-09T01:33:54.545706Z","steps":["trace[859829413] 'read index received' (duration: 121.41729ms)","trace[859829413] 'applied index is now lower than readState.Index' (duration: 1.119109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:33:54.545946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.761076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:33:54.54595Z","caller":"traceutil/trace.go:171","msg":"trace[1189013553] transaction","detail":"{read_only:false; response_revision:12184; number_of_response:1; }","duration":"214.697499ms","start":"2026-09-09T01:33:54.331232Z","end":"2026-09-09T01:33:54.545929Z","steps":["trace[1189013553] 'process raft request' (duration: 214.321309ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:33:54.545991Z","caller":"traceutil/trace.go:171","msg":"trace[2126615497] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12184; }","duration":"122.863998ms","start":"2026-09-09T01:33:54.423112Z","end":"2026-09-09T01:33:54.545976Z","steps":["trace[2126615497] 'agreement among raft nodes before linearized reading' (duration: 122.702434ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:34:58.039082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10434} {"level":"info","ts":"2026-09-09T01:34:58.138451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10434,"took":"96.548555ms","hash":858209150,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":25878528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T01:34:58.138532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":858209150,"revision":10434,"compact-revision":8041} {"level":"info","ts":"2026-09-09T01:39:58.047467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12611} {"level":"info","ts":"2026-09-09T01:39:58.13579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12611,"took":"85.036694ms","hash":790170920,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:39:58.13589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":790170920,"revision":12611,"compact-revision":10434} {"level":"info","ts":"2026-09-09T01:40:35.761731Z","caller":"traceutil/trace.go:171","msg":"trace[168697984] transaction","detail":"{read_only:false; response_revision:14853; number_of_response:1; }","duration":"116.241942ms","start":"2026-09-09T01:40:35.645449Z","end":"2026-09-09T01:40:35.761691Z","steps":["trace[168697984] 'process raft request' (duration: 116.003926ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:35.776056Z","caller":"traceutil/trace.go:171","msg":"trace[106749421] linearizableReadLoop","detail":"{readStateIndex:15371; appliedIndex:15368; }","duration":"120.812522ms","start":"2026-09-09T01:40:35.655203Z","end":"2026-09-09T01:40:35.776016Z","steps":["trace[106749421] 'read index received' (duration: 106.241037ms)","trace[106749421] 'applied index is now lower than readState.Index' (duration: 14.569765ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:40:35.776396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.174352ms","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-09T01:40:35.77645Z","caller":"traceutil/trace.go:171","msg":"trace[899276645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14855; }","duration":"121.259005ms","start":"2026-09-09T01:40:35.655177Z","end":"2026-09-09T01:40:35.776436Z","steps":["trace[899276645] 'agreement among raft nodes before linearized reading' (duration: 120.984797ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:35.776937Z","caller":"traceutil/trace.go:171","msg":"trace[923873285] transaction","detail":"{read_only:false; response_revision:14854; number_of_response:1; }","duration":"130.994382ms","start":"2026-09-09T01:40:35.645931Z","end":"2026-09-09T01:40:35.776925Z","steps":["trace[923873285] 'process raft request' (duration: 129.79226ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:35.77709Z","caller":"traceutil/trace.go:171","msg":"trace[1516764358] transaction","detail":"{read_only:false; response_revision:14855; number_of_response:1; }","duration":"130.689974ms","start":"2026-09-09T01:40:35.646391Z","end":"2026-09-09T01:40:35.777081Z","steps":["trace[1516764358] 'process raft request' (duration: 129.562624ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:53.453288Z","caller":"traceutil/trace.go:171","msg":"trace[1857512573] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"201.740041ms","start":"2026-09-09T01:40:53.251508Z","end":"2026-09-09T01:40:53.453248Z","steps":["trace[1857512573] 'process raft request' (duration: 201.32368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.382207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16924422412251389553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-09T01:40:54.525667Z","caller":"traceutil/trace.go:171","msg":"trace[926506747] transaction","detail":"{read_only:false; response_revision:14960; number_of_response:1; }","duration":"647.08684ms","start":"2026-09-09T01:40:53.878561Z","end":"2026-09-09T01:40:54.525648Z","steps":["trace[926506747] 'process raft request' (duration: 646.795222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.525868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:53.87854Z","time spent":"647.192103ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:40:54.526028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.675566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-09T01:40:54.526184Z","caller":"traceutil/trace.go:171","msg":"trace[1628942219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14960; }","duration":"644.919293ms","start":"2026-09-09T01:40:53.881235Z","end":"2026-09-09T01:40:54.526154Z","steps":["trace[1628942219] 'agreement among raft nodes before linearized reading' (duration: 644.552493ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:54.525589Z","caller":"traceutil/trace.go:171","msg":"trace[409371449] linearizableReadLoop","detail":"{readStateIndex:15481; appliedIndex:15480; }","duration":"644.234144ms","start":"2026-09-09T01:40:53.881326Z","end":"2026-09-09T01:40:54.525561Z","steps":["trace[409371449] 'read index received' (duration: 644.05353ms)","trace[409371449] 'applied index is now lower than readState.Index' (duration: 179.474µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:40:54.52625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:53.881212Z","time spent":"645.022445ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T01:40:54.640582Z","caller":"traceutil/trace.go:171","msg":"trace[898049470] linearizableReadLoop","detail":"{readStateIndex:15483; appliedIndex:15481; }","duration":"114.160527ms","start":"2026-09-09T01:40:54.52639Z","end":"2026-09-09T01:40:54.64055Z","steps":["trace[898049470] 'read index received' (duration: 113.941641ms)","trace[898049470] 'applied index is now lower than readState.Index' (duration: 217.756µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:40:54.640642Z","caller":"traceutil/trace.go:171","msg":"trace[204835762] transaction","detail":"{read_only:false; response_revision:14961; number_of_response:1; }","duration":"251.166787ms","start":"2026-09-09T01:40:54.389408Z","end":"2026-09-09T01:40:54.640574Z","steps":["trace[204835762] 'process raft request' (duration: 250.920101ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:54.640732Z","caller":"traceutil/trace.go:171","msg":"trace[121256280] transaction","detail":"{read_only:false; response_revision:14962; number_of_response:1; }","duration":"240.502225ms","start":"2026-09-09T01:40:54.400201Z","end":"2026-09-09T01:40:54.640703Z","steps":["trace[121256280] 'process raft request' (duration: 240.299699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.641201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.375511ms","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-09T01:40:54.641257Z","caller":"traceutil/trace.go:171","msg":"trace[258506825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14962; }","duration":"755.469743ms","start":"2026-09-09T01:40:53.885772Z","end":"2026-09-09T01:40:54.641242Z","steps":["trace[258506825] 'agreement among raft nodes before linearized reading' (duration: 755.285078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.641229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.179112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T01:40:54.641335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.730503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-mk77p\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-09T01:40:54.641393Z","caller":"traceutil/trace.go:171","msg":"trace[985005503] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:14962; }","duration":"437.423699ms","start":"2026-09-09T01:40:54.203946Z","end":"2026-09-09T01:40:54.641369Z","steps":["trace[985005503] 'agreement among raft nodes before linearized reading' (duration: 437.107741ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:54.641424Z","caller":"traceutil/trace.go:171","msg":"trace[1291528829] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-mk77p; range_end:; response_count:1; response_revision:14962; }","duration":"592.846586ms","start":"2026-09-09T01:40:54.048564Z","end":"2026-09-09T01:40:54.64141Z","steps":["trace[1291528829] 'agreement among raft nodes before linearized reading' (duration: 592.567349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.641443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:54.203923Z","time spent":"437.507171ms","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:40:54.641463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:54.048549Z","time spent":"592.906148ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12384,"request content":"key:\"/registry/pods/openstack/openvswitch-mk77p\" "} {"level":"warn","ts":"2026-09-09T01:40:54.641321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:53.885757Z","time spent":"755.555166ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:40:54.641519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.6796ms","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-09T01:40:54.641708Z","caller":"traceutil/trace.go:171","msg":"trace[1364766141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14962; }","duration":"752.920346ms","start":"2026-09-09T01:40:53.88872Z","end":"2026-09-09T01:40:54.641641Z","steps":["trace[1364766141] 'agreement among raft nodes before linearized reading' (duration: 752.311709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.641824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:40:53.888706Z","time spent":"753.099231ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-09T01:40:54.669669Z","caller":"traceutil/trace.go:171","msg":"trace[163408660] transaction","detail":"{read_only:false; response_revision:14963; number_of_response:1; }","duration":"137.795892ms","start":"2026-09-09T01:40:54.531842Z","end":"2026-09-09T01:40:54.669638Z","steps":["trace[163408660] 'process raft request' (duration: 137.647398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.66989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.771418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:40:54.670018Z","caller":"traceutil/trace.go:171","msg":"trace[499831665] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14963; }","duration":"107.951083ms","start":"2026-09-09T01:40:54.562033Z","end":"2026-09-09T01:40:54.669984Z","steps":["trace[499831665] 'agreement among raft nodes before linearized reading' (duration: 107.627054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:04.886213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.999197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:41:04.886375Z","caller":"traceutil/trace.go:171","msg":"trace[1607172750] transaction","detail":"{read_only:false; response_revision:15135; number_of_response:1; }","duration":"204.36244ms","start":"2026-09-09T01:41:04.681989Z","end":"2026-09-09T01:41:04.886351Z","steps":["trace[1607172750] 'process raft request' (duration: 94.123357ms)","trace[1607172750] 'compare' (duration: 109.71887ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:41:04.932928Z","caller":"traceutil/trace.go:171","msg":"trace[886890055] linearizableReadLoop","detail":"{readStateIndex:15661; appliedIndex:15659; }","duration":"224.01823ms","start":"2026-09-09T01:41:04.708878Z","end":"2026-09-09T01:41:04.932896Z","steps":["trace[886890055] 'read index received' (duration: 65.43348ms)","trace[886890055] 'applied index is now lower than readState.Index' (duration: 158.58386ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:41:04.93305Z","caller":"traceutil/trace.go:171","msg":"trace[70290650] transaction","detail":"{read_only:false; response_revision:15136; number_of_response:1; }","duration":"249.7576ms","start":"2026-09-09T01:41:04.683237Z","end":"2026-09-09T01:41:04.932994Z","steps":["trace[70290650] 'process raft request' (duration: 249.454432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:04.933263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.355138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:41:04.933346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.255036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-09T01:41:04.933268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.687287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:41:04.933467Z","caller":"traceutil/trace.go:171","msg":"trace[1677015] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:15136; }","duration":"129.969084ms","start":"2026-09-09T01:41:04.803476Z","end":"2026-09-09T01:41:04.933446Z","steps":["trace[1677015] 'agreement among raft nodes before linearized reading' (duration: 129.690946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:04.933494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.174551ms","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-09T01:41:04.933544Z","caller":"traceutil/trace.go:171","msg":"trace[193106304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15136; }","duration":"183.251142ms","start":"2026-09-09T01:41:04.750278Z","end":"2026-09-09T01:41:04.933529Z","steps":["trace[193106304] 'agreement among raft nodes before linearized reading' (duration: 183.140049ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:04.933396Z","caller":"traceutil/trace.go:171","msg":"trace[387113391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15136; }","duration":"193.37282ms","start":"2026-09-09T01:41:04.740005Z","end":"2026-09-09T01:41:04.933378Z","steps":["trace[387113391] 'agreement among raft nodes before linearized reading' (duration: 193.138954ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:04.933378Z","caller":"traceutil/trace.go:171","msg":"trace[1856675590] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:15136; }","duration":"224.556834ms","start":"2026-09-09T01:41:04.708799Z","end":"2026-09-09T01:41:04.933356Z","steps":["trace[1856675590] 'agreement among raft nodes before linearized reading' (duration: 224.324038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:04.933687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.129123ms","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-09T01:41:04.93386Z","caller":"traceutil/trace.go:171","msg":"trace[1860801156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15136; }","duration":"168.291367ms","start":"2026-09-09T01:41:04.765502Z","end":"2026-09-09T01:41:04.933793Z","steps":["trace[1860801156] 'agreement among raft nodes before linearized reading' (duration: 167.929727ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:05.204453Z","caller":"traceutil/trace.go:171","msg":"trace[783414100] linearizableReadLoop","detail":"{readStateIndex:15664; appliedIndex:15662; }","duration":"237.594959ms","start":"2026-09-09T01:41:04.96683Z","end":"2026-09-09T01:41:05.204425Z","steps":["trace[783414100] 'read index received' (duration: 220.406404ms)","trace[783414100] 'applied index is now lower than readState.Index' (duration: 17.187385ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:41:05.204511Z","caller":"traceutil/trace.go:171","msg":"trace[168515720] transaction","detail":"{read_only:false; response_revision:15138; number_of_response:1; }","duration":"265.597328ms","start":"2026-09-09T01:41:04.938878Z","end":"2026-09-09T01:41:05.204475Z","steps":["trace[168515720] 'process raft request' (duration: 248.441175ms)","trace[168515720] 'compare' (duration: 16.898966ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:41:05.204562Z","caller":"traceutil/trace.go:171","msg":"trace[1103979185] transaction","detail":"{read_only:false; response_revision:15139; number_of_response:1; }","duration":"265.447414ms","start":"2026-09-09T01:41:04.939068Z","end":"2026-09-09T01:41:05.204515Z","steps":["trace[1103979185] 'process raft request' (duration: 265.309571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:05.204678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.822605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:41:05.204723Z","caller":"traceutil/trace.go:171","msg":"trace[833754618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:15139; }","duration":"237.923907ms","start":"2026-09-09T01:41:04.966785Z","end":"2026-09-09T01:41:05.204709Z","steps":["trace[833754618] 'agreement among raft nodes before linearized reading' (duration: 237.830705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:41:05.204897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.068022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-sbddj.18d3838029cf8e7e\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-09-09T01:41:05.205005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.201549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:41:05.205034Z","caller":"traceutil/trace.go:171","msg":"trace[398812140] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-sbddj.18d3838029cf8e7e; range_end:; response_count:1; response_revision:15139; }","duration":"190.254168ms","start":"2026-09-09T01:41:05.014747Z","end":"2026-09-09T01:41:05.205001Z","steps":["trace[398812140] 'agreement among raft nodes before linearized reading' (duration: 189.919769ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:05.205056Z","caller":"traceutil/trace.go:171","msg":"trace[1581225734] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:15139; }","duration":"122.314372ms","start":"2026-09-09T01:41:05.082728Z","end":"2026-09-09T01:41:05.205043Z","steps":["trace[1581225734] 'agreement among raft nodes before linearized reading' (duration: 122.167118ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:42:47.349626Z","caller":"traceutil/trace.go:171","msg":"trace[729581784] transaction","detail":"{read_only:false; response_revision:16243; number_of_response:1; }","duration":"122.182049ms","start":"2026-09-09T01:42:47.227415Z","end":"2026-09-09T01:42:47.349597Z","steps":["trace[729581784] 'process raft request' (duration: 121.925832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:47.610185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.342954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:42:47.610351Z","caller":"traceutil/trace.go:171","msg":"trace[1410092000] linearizableReadLoop","detail":"{readStateIndex:16804; appliedIndex:16802; }","duration":"197.631562ms","start":"2026-09-09T01:42:47.412701Z","end":"2026-09-09T01:42:47.610333Z","steps":["trace[1410092000] 'read index received' (duration: 36.011261ms)","trace[1410092000] 'applied index is now lower than readState.Index' (duration: 161.619021ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:42:47.610434Z","caller":"traceutil/trace.go:171","msg":"trace[36501246] transaction","detail":"{read_only:false; response_revision:16244; number_of_response:1; }","duration":"284.072977ms","start":"2026-09-09T01:42:47.326352Z","end":"2026-09-09T01:42:47.610425Z","steps":["trace[36501246] 'process raft request' (duration: 122.353833ms)","trace[36501246] 'compare' (duration: 159.755382ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:42:47.610572Z","caller":"traceutil/trace.go:171","msg":"trace[1053843230] transaction","detail":"{read_only:false; response_revision:16245; number_of_response:1; }","duration":"242.395595ms","start":"2026-09-09T01:42:47.368168Z","end":"2026-09-09T01:42:47.610563Z","steps":["trace[1053843230] 'process raft request' (duration: 242.113138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:47.61064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.93972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:42:47.610663Z","caller":"traceutil/trace.go:171","msg":"trace[1970433733] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16245; }","duration":"198.001602ms","start":"2026-09-09T01:42:47.412654Z","end":"2026-09-09T01:42:47.610656Z","steps":["trace[1970433733] 'agreement among raft nodes before linearized reading' (duration: 197.959441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:47.611013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.89403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x2894\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-09-09T01:42:47.611101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.3057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-09T01:42:47.611139Z","caller":"traceutil/trace.go:171","msg":"trace[1581767553] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x2894; range_end:; response_count:1; response_revision:16245; }","duration":"139.017044ms","start":"2026-09-09T01:42:47.472063Z","end":"2026-09-09T01:42:47.61108Z","steps":["trace[1581767553] 'agreement among raft nodes before linearized reading' (duration: 138.811908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:47.611441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.028793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-09T01:42:47.611534Z","caller":"traceutil/trace.go:171","msg":"trace[661956784] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16245; }","duration":"198.144275ms","start":"2026-09-09T01:42:47.413373Z","end":"2026-09-09T01:42:47.611517Z","steps":["trace[661956784] 'agreement among raft nodes before linearized reading' (duration: 197.690433ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:42:47.611228Z","caller":"traceutil/trace.go:171","msg":"trace[1318302334] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16245; }","duration":"198.362721ms","start":"2026-09-09T01:42:47.412765Z","end":"2026-09-09T01:42:47.611128Z","steps":["trace[1318302334] 'agreement among raft nodes before linearized reading' (duration: 198.024092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:47.61101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.406415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x2894\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-09-09T01:42:47.611843Z","caller":"traceutil/trace.go:171","msg":"trace[1791137745] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x2894; range_end:; response_count:1; response_revision:16245; }","duration":"106.282899ms","start":"2026-09-09T01:42:47.505546Z","end":"2026-09-09T01:42:47.611829Z","steps":["trace[1791137745] 'agreement among raft nodes before linearized reading' (duration: 105.272202ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:58.057817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14613} {"level":"info","ts":"2026-09-09T01:44:58.154137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14613,"took":"92.072392ms","hash":3839474800,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:44:58.154239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839474800,"revision":14613,"compact-revision":12611} {"level":"warn","ts":"2026-09-09T01:48:30.331617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.382116ms","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-09T01:48:30.331733Z","caller":"traceutil/trace.go:171","msg":"trace[1713283986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18724; }","duration":"113.55156ms","start":"2026-09-09T01:48:30.218156Z","end":"2026-09-09T01:48:30.331708Z","steps":["trace[1713283986] 'range keys from in-memory index tree' (duration: 113.175381ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:31.434222Z","caller":"traceutil/trace.go:171","msg":"trace[1528547014] transaction","detail":"{read_only:false; response_revision:18727; number_of_response:1; }","duration":"185.986184ms","start":"2026-09-09T01:48:31.24821Z","end":"2026-09-09T01:48:31.434196Z","steps":["trace[1528547014] 'process raft request' (duration: 185.81145ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:31.574222Z","caller":"traceutil/trace.go:171","msg":"trace[1371859256] linearizableReadLoop","detail":"{readStateIndex:19370; appliedIndex:19369; }","duration":"265.970327ms","start":"2026-09-09T01:48:31.308218Z","end":"2026-09-09T01:48:31.574188Z","steps":["trace[1371859256] 'read index received' (duration: 126.02111ms)","trace[1371859256] 'applied index is now lower than readState.Index' (duration: 139.948277ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:48:31.574432Z","caller":"traceutil/trace.go:171","msg":"trace[753143166] transaction","detail":"{read_only:false; response_revision:18728; number_of_response:1; }","duration":"284.037365ms","start":"2026-09-09T01:48:31.290358Z","end":"2026-09-09T01:48:31.574395Z","steps":["trace[753143166] 'process raft request' (duration: 214.035025ms)","trace[753143166] 'compare' (duration: 69.64028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:48:31.574544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.289146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.33\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-09T01:48:31.574657Z","caller":"traceutil/trace.go:171","msg":"trace[1266278079] range","detail":"{range_begin:/registry/masterleases/162.253.55.33; range_end:; response_count:1; response_revision:18728; }","duration":"266.46313ms","start":"2026-09-09T01:48:31.308168Z","end":"2026-09-09T01:48:31.574631Z","steps":["trace[1266278079] 'agreement among raft nodes before linearized reading' (duration: 266.212284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:31.574665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.867061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mcfh6\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-09-09T01:48:31.574746Z","caller":"traceutil/trace.go:171","msg":"trace[947413306] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mcfh6; range_end:; response_count:1; response_revision:18728; }","duration":"241.992614ms","start":"2026-09-09T01:48:31.332732Z","end":"2026-09-09T01:48:31.574725Z","steps":["trace[947413306] 'agreement among raft nodes before linearized reading' (duration: 241.726187ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:18.050967Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b85a21b07385eadf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:49:18.057249Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:49:18.057374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:49:58.067968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17125} {"level":"info","ts":"2026-09-09T01:49:58.172128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17125,"took":"98.979345ms","hash":1469195796,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":33849344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T01:49:58.172231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1469195796,"revision":17125,"compact-revision":14613} {"level":"info","ts":"2026-09-09T01:54:14.792281Z","caller":"traceutil/trace.go:171","msg":"trace[1614124467] transaction","detail":"{read_only:false; response_revision:21419; number_of_response:1; }","duration":"120.004425ms","start":"2026-09-09T01:54:14.672241Z","end":"2026-09-09T01:54:14.792246Z","steps":["trace[1614124467] 'process raft request' (duration: 119.78391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:31.447072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.903488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:54:31.447202Z","caller":"traceutil/trace.go:171","msg":"trace[1629282170] transaction","detail":"{read_only:false; response_revision:21504; number_of_response:1; }","duration":"259.742712ms","start":"2026-09-09T01:54:31.187437Z","end":"2026-09-09T01:54:31.44718Z","steps":["trace[1629282170] 'process raft request' (duration: 110.623148ms)","trace[1629282170] 'compare' (duration: 148.750054ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:54:31.700729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.419675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:54:31.700882Z","caller":"traceutil/trace.go:171","msg":"trace[776888155] linearizableReadLoop","detail":"{readStateIndex:22240; appliedIndex:22238; }","duration":"441.584629ms","start":"2026-09-09T01:54:31.259278Z","end":"2026-09-09T01:54:31.700863Z","steps":["trace[776888155] 'read index received' (duration: 38.777383ms)","trace[776888155] 'applied index is now lower than readState.Index' (duration: 402.805096ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:54:31.701324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.130854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.33\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-09T01:54:31.701436Z","caller":"traceutil/trace.go:171","msg":"trace[1298305626] range","detail":"{range_begin:/registry/masterleases/162.253.55.33; range_end:; response_count:1; response_revision:21505; }","duration":"359.291949ms","start":"2026-09-09T01:54:31.342126Z","end":"2026-09-09T01:54:31.701418Z","steps":["trace[1298305626] 'agreement among raft nodes before linearized reading' (duration: 359.021681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:31.70139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.703491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T01:54:31.701482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:54:31.342079Z","time spent":"359.391741ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.33\" "} {"level":"warn","ts":"2026-09-09T01:54:31.701497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.214956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-09T01:54:31.700926Z","caller":"traceutil/trace.go:171","msg":"trace[1285429577] transaction","detail":"{read_only:false; response_revision:21505; number_of_response:1; }","duration":"498.059889ms","start":"2026-09-09T01:54:31.202836Z","end":"2026-09-09T01:54:31.700896Z","steps":["trace[1285429577] 'process raft request' (duration: 393.358487ms)","trace[1285429577] 'compare' (duration: 104.072485ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:54:31.70163Z","caller":"traceutil/trace.go:171","msg":"trace[1091144108] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:21505; }","duration":"442.412791ms","start":"2026-09-09T01:54:31.259196Z","end":"2026-09-09T01:54:31.701608Z","steps":["trace[1091144108] 'agreement among raft nodes before linearized reading' (duration: 441.904367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:31.701715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:54:31.259159Z","time spent":"442.541574ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-09-09T01:54:31.701532Z","caller":"traceutil/trace.go:171","msg":"trace[1789484079] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:21505; }","duration":"256.934228ms","start":"2026-09-09T01:54:31.444565Z","end":"2026-09-09T01:54:31.701499Z","steps":["trace[1789484079] 'agreement among raft nodes before linearized reading' (duration: 256.62835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:31.701792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:54:31.202823Z","time spent":"498.850309ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:54:32.103545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.637067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T01:54:32.10362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.833092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T01:54:32.103675Z","caller":"traceutil/trace.go:171","msg":"trace[804477085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21507; }","duration":"265.830862ms","start":"2026-09-09T01:54:31.837816Z","end":"2026-09-09T01:54:32.103647Z","steps":["trace[804477085] 'range keys from in-memory index tree' (duration: 265.522974ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:32.103695Z","caller":"traceutil/trace.go:171","msg":"trace[1155077062] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:21507; }","duration":"196.971296ms","start":"2026-09-09T01:54:31.906705Z","end":"2026-09-09T01:54:32.103676Z","steps":["trace[1155077062] 'count revisions from in-memory index tree' (duration: 196.715959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:32.103941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.491404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-09T01:54:32.104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.271023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T01:54:32.104024Z","caller":"traceutil/trace.go:171","msg":"trace[913253065] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:21507; }","duration":"229.579086ms","start":"2026-09-09T01:54:31.874427Z","end":"2026-09-09T01:54:32.104007Z","steps":["trace[913253065] 'count revisions from in-memory index tree' (duration: 229.367331ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:32.104082Z","caller":"traceutil/trace.go:171","msg":"trace[2003106077] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:21507; }","duration":"283.413976ms","start":"2026-09-09T01:54:31.820654Z","end":"2026-09-09T01:54:32.104068Z","steps":["trace[2003106077] 'count revisions from in-memory index tree' (duration: 283.134969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:32.104133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.644771ms","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-09T01:54:32.104187Z","caller":"traceutil/trace.go:171","msg":"trace[508521493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21507; }","duration":"275.877248ms","start":"2026-09-09T01:54:31.82829Z","end":"2026-09-09T01:54:32.104167Z","steps":["trace[508521493] 'range keys from in-memory index tree' (duration: 275.068406ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:58.076169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19658} {"level":"info","ts":"2026-09-09T01:54:58.217885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19658,"took":"137.686792ms","hash":824207258,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":28987392,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T01:54:58.217963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":824207258,"revision":19658,"compact-revision":17125} {"level":"info","ts":"2026-09-09T01:58:28.374289Z","caller":"traceutil/trace.go:171","msg":"trace[987985177] transaction","detail":"{read_only:false; response_revision:23827; number_of_response:1; }","duration":"100.485531ms","start":"2026-09-09T01:58:28.273776Z","end":"2026-09-09T01:58:28.374261Z","steps":["trace[987985177] 'process raft request' (duration: 93.579908ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:59:58.084254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21647} {"level":"info","ts":"2026-09-09T01:59:58.199172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21647,"took":"111.476991ms","hash":689331480,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":43315200,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-09T01:59:58.199244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":689331480,"revision":21647,"compact-revision":19658} {"level":"info","ts":"2026-09-09T02:04:58.094551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24903} {"level":"info","ts":"2026-09-09T02:04:58.281788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24903,"took":"169.284005ms","hash":501459957,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T02:04:58.28188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":501459957,"revision":24903,"compact-revision":21647} {"level":"info","ts":"2026-09-09T02:05:47.125532Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006f9d.wal"} {"level":"info","ts":"2026-09-09T02:08:58.884038Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b85a21b07385eadf","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T02:08:58.891852Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-09T02:08:58.891974Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}